builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-046 starttime: 1465346850.86 results: success (0) buildid: 20160607155851 builduid: 9bb1c4725faa48e3b24f216d1905a0f8 revision: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:30.856688) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:30.857097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:30.857369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:30.874603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:30.874863) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 17:47:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.0M=0s 2016-06-07 17:47:31 (32.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113119 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.001451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.001775) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017533 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.051451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.051823) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev dc4d7f68030e9edd22ad0bb0bd2244d047dd767d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev dc4d7f68030e9edd22ad0bb0bd2244d047dd767d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 17:47:31,406 truncating revision to first 12 chars 2016-06-07 17:47:31,406 Setting DEBUG logging. 2016-06-07 17:47:31,406 attempt 1/10 2016-06-07 17:47:31,406 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/dc4d7f68030e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 17:47:31,710 unpacking tar archive at: autoland-dc4d7f68030e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628164 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.693267) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.693612) ========= script_repo_revision: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.693989) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.694258) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 17:47:31.703575) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-07 17:47:31.703847) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:47:32 INFO - MultiFileLogger online at 20160607 17:47:32 in /builds/slave/test 17:47:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 17:47:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:47:32 INFO - {'append_to_log': False, 17:47:32 INFO - 'base_work_dir': '/builds/slave/test', 17:47:32 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 17:47:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:47:32 INFO - 'branch': 'Autoland-Non-PGO', 17:47:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:47:32 INFO - 'config_files': ('talos/linux_config.py',), 17:47:32 INFO - 'default_actions': ('clobber', 17:47:32 INFO - 'read-buildbot-config', 17:47:32 INFO - 'download-and-extract', 17:47:32 INFO - 'populate-webroot', 17:47:32 INFO - 'create-virtualenv', 17:47:32 INFO - 'install', 17:47:32 INFO - 'run-tests'), 17:47:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:47:32 INFO - 'download_minidump_stackwalk': True, 17:47:32 INFO - 'download_symbols': 'ondemand', 17:47:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:47:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:47:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:47:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:47:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:47:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:47:32 INFO - 'installer_path': 'installer.exe', 17:47:32 INFO - 'log_level': 'info', 17:47:32 INFO - 'log_name': 'talos', 17:47:32 INFO - 'log_to_console': True, 17:47:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:47:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:47:32 INFO - 'opt_config_files': (), 17:47:32 INFO - 'pip_index': False, 17:47:32 INFO - 'sps_profile': False, 17:47:32 INFO - 'sps_profile_interval': 0, 17:47:32 INFO - 'suite': 'dromaeojs', 17:47:32 INFO - 'system_bits': '32', 17:47:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:47:32 INFO - 'title': 'talos-linux64-ix-046', 17:47:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:47:32 INFO - 'use_talos_json': True, 17:47:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:47:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:47:32 INFO - 'work_dir': 'build'} 17:47:32 INFO - ##### 17:47:32 INFO - ##### Running clobber step. 17:47:32 INFO - ##### 17:47:32 INFO - Running pre-action listener: _resource_record_pre_action 17:47:32 INFO - Running main action method: clobber 17:47:32 INFO - rmtree: /builds/slave/test/build 17:47:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:47:32 INFO - Running post-action listener: _resource_record_post_action 17:47:32 INFO - ##### 17:47:32 INFO - ##### Finished clobber step (success) 17:47:32 INFO - ##### 17:47:32 INFO - ##### 17:47:32 INFO - ##### Running read-buildbot-config step. 17:47:32 INFO - ##### 17:47:32 INFO - Running pre-action listener: _resource_record_pre_action 17:47:32 INFO - Running main action method: read_buildbot_config 17:47:32 INFO - Using buildbot properties: 17:47:32 INFO - { 17:47:32 INFO - "project": "", 17:47:32 INFO - "product": "firefox", 17:47:32 INFO - "script_repo_revision": "production", 17:47:32 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 17:47:32 INFO - "repository": "", 17:47:32 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 17:47:32 INFO - "buildid": "20160607155851", 17:47:32 INFO - "pgo_build": "False", 17:47:32 INFO - "basedir": "/builds/slave/test", 17:47:32 INFO - "buildnumber": 0, 17:47:32 INFO - "slavename": "talos-linux64-ix-046", 17:47:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:47:32 INFO - "platform": "ubuntu64_hw", 17:47:32 INFO - "scheduler": "tests-autoland-linux64-talos", 17:47:32 INFO - "branch": "autoland", 17:47:32 INFO - "repo_path": "integration/autoland", 17:47:32 INFO - "revision": "dc4d7f68030e9edd22ad0bb0bd2244d047dd767d", 17:47:32 INFO - "stage_platform": "linux64", 17:47:32 INFO - "builduid": "9bb1c4725faa48e3b24f216d1905a0f8", 17:47:32 INFO - "slavebuilddir": "test" 17:47:32 INFO - } 17:47:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:47:32 INFO - Running post-action listener: _resource_record_post_action 17:47:32 INFO - ##### 17:47:32 INFO - ##### Finished read-buildbot-config step (success) 17:47:32 INFO - ##### 17:47:32 INFO - ##### 17:47:32 INFO - ##### Running download-and-extract step. 17:47:32 INFO - ##### 17:47:32 INFO - Running pre-action listener: _resource_record_pre_action 17:47:32 INFO - Running main action method: download_and_extract 17:47:32 INFO - mkdir: /builds/slave/test/build/tests 17:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:32 INFO - https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:47:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:47:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:47:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:47:33 INFO - Downloaded 1361 bytes. 17:47:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:47:33 INFO - Using the following test package requirements: 17:47:33 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:47:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:47:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:47:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'jsshell-linux-x86_64.zip'], 17:47:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:47:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:47:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:47:33 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:47:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:47:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:47:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:47:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:33 INFO - https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:47:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:47:34 INFO - Downloaded 22837932 bytes. 17:47:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:47:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:47:35 INFO - Return code: 0 17:47:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:35 INFO - https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:47:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:47:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:47:36 INFO - Downloaded 22837932 bytes. 17:47:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:47:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:47:38 INFO - Return code: 0 17:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:38 INFO - https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:47:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:47:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:47:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:47:40 INFO - Downloaded 10834055 bytes. 17:47:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:47:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:47:40 INFO - Return code: 0 17:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:40 INFO - https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:47:41 INFO - Downloaded 54995634 bytes. 17:47:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:47:41 INFO - mkdir: /builds/slave/test/properties 17:47:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:47:41 INFO - Writing to file /builds/slave/test/properties/build_url 17:47:41 INFO - Contents: 17:47:41 INFO - build_url:https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:47:42 INFO - Running post-action listener: _resource_record_post_action 17:47:42 INFO - Running post-action listener: set_extra_try_arguments 17:47:42 INFO - ##### 17:47:42 INFO - ##### Finished download-and-extract step (success) 17:47:42 INFO - ##### 17:47:42 INFO - ##### 17:47:42 INFO - ##### Running populate-webroot step. 17:47:42 INFO - ##### 17:47:42 INFO - Running pre-action listener: _resource_record_pre_action 17:47:42 INFO - Running main action method: populate_webroot 17:47:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:47:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:47:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:47:42 INFO - '--tppagecycles', 17:47:42 INFO - '7'], 17:47:42 INFO - 'tests': ['tsvgm']}}, 17:47:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:47:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:47:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:47:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:47:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:47:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:47:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['damp', 'tps']}, 17:47:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'tests': ['damp', 'tps']}, 17:47:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['dromaeo_dom']}, 17:47:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:47:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['a11yr', 17:47:42 INFO - 'ts_paint', 17:47:42 INFO - 'tpaint', 17:47:42 INFO - 'sessionrestore', 17:47:42 INFO - 'sessionrestore_no_auto_restore', 17:47:42 INFO - 'tabpaint']}, 17:47:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['tsvgx', 17:47:42 INFO - 'tsvgr_opacity', 17:47:42 INFO - 'tart', 17:47:42 INFO - 'tscrollx', 17:47:42 INFO - 'cart']}, 17:47:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:47:42 INFO - 'tsvgr_opacity', 17:47:42 INFO - 'tart', 17:47:42 INFO - 'tscrollx', 17:47:42 INFO - 'cart']}, 17:47:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'talos_options': ['--disable-e10s'], 17:47:42 INFO - 'tests': ['tp5o']}, 17:47:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'tests': ['tp5o']}, 17:47:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'talos_options': ['--disable-e10s', 17:47:42 INFO - '--xperf_path', 17:47:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:47:42 INFO - 'tests': ['tp5n']}, 17:47:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:47:42 INFO - 'talos_options': ['--xperf_path', 17:47:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:47:42 INFO - 'tests': ['tp5n']}}, 17:47:42 INFO - 'talos.zip': {'path': '', 17:47:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:47:42 INFO - Running post-action listener: _resource_record_post_action 17:47:42 INFO - ##### 17:47:42 INFO - ##### Finished populate-webroot step (success) 17:47:42 INFO - ##### 17:47:42 INFO - ##### 17:47:42 INFO - ##### Running create-virtualenv step. 17:47:42 INFO - ##### 17:47:42 INFO - Running pre-action listener: _resource_record_pre_action 17:47:42 INFO - Running main action method: create_virtualenv 17:47:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:47:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:47:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:47:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:47:42 INFO - Using real prefix '/usr' 17:47:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:47:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:47:45 INFO - Installing pip.................done. 17:47:45 INFO - Return code: 0 17:47:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:47:45 INFO - Using env: {'DISPLAY': ':0', 17:47:45 INFO - 'HOME': '/home/cltbld', 17:47:45 INFO - 'LANG': 'en_US.UTF-8', 17:47:45 INFO - 'LANGUAGE': 'en_US:en', 17:47:45 INFO - 'LOGNAME': 'cltbld', 17:47:45 INFO - 'MAIL': '/var/mail/cltbld', 17:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:45 INFO - 'MOZ_NO_REMOTE': '1', 17:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:45 INFO - 'NO_EM_RESTART': '1', 17:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:45 INFO - 'PWD': '/builds/slave/test', 17:47:45 INFO - 'SHELL': '/bin/bash', 17:47:45 INFO - 'SHLVL': '1', 17:47:45 INFO - 'TERM': 'linux', 17:47:45 INFO - 'TMOUT': '86400', 17:47:45 INFO - 'USER': 'cltbld', 17:47:45 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:45 INFO - '_': '/tools/buildbot/bin/python'} 17:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:47:45 INFO - Cleaning up... 17:47:45 INFO - Return code: 0 17:47:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:47:45 INFO - Using env: {'DISPLAY': ':0', 17:47:45 INFO - 'HOME': '/home/cltbld', 17:47:45 INFO - 'LANG': 'en_US.UTF-8', 17:47:45 INFO - 'LANGUAGE': 'en_US:en', 17:47:45 INFO - 'LOGNAME': 'cltbld', 17:47:45 INFO - 'MAIL': '/var/mail/cltbld', 17:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:45 INFO - 'MOZ_NO_REMOTE': '1', 17:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:45 INFO - 'NO_EM_RESTART': '1', 17:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:45 INFO - 'PWD': '/builds/slave/test', 17:47:45 INFO - 'SHELL': '/bin/bash', 17:47:45 INFO - 'SHLVL': '1', 17:47:45 INFO - 'TERM': 'linux', 17:47:45 INFO - 'TMOUT': '86400', 17:47:45 INFO - 'USER': 'cltbld', 17:47:45 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:45 INFO - '_': '/tools/buildbot/bin/python'} 17:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:45 INFO - Downloading/unpacking psutil>=3.1.1 17:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:47:48 INFO - Installing collected packages: psutil 17:47:48 INFO - Running setup.py install for psutil 17:47:48 INFO - building 'psutil._psutil_linux' extension 17:47:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:47:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:47:49 INFO - building 'psutil._psutil_posix' extension 17:47:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:47:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:47:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:47:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:47:49 INFO - Successfully installed psutil 17:47:49 INFO - Cleaning up... 17:47:49 INFO - Return code: 0 17:47:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:47:49 INFO - Using env: {'DISPLAY': ':0', 17:47:49 INFO - 'HOME': '/home/cltbld', 17:47:49 INFO - 'LANG': 'en_US.UTF-8', 17:47:49 INFO - 'LANGUAGE': 'en_US:en', 17:47:49 INFO - 'LOGNAME': 'cltbld', 17:47:49 INFO - 'MAIL': '/var/mail/cltbld', 17:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:49 INFO - 'MOZ_NO_REMOTE': '1', 17:47:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:49 INFO - 'NO_EM_RESTART': '1', 17:47:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:49 INFO - 'PWD': '/builds/slave/test', 17:47:49 INFO - 'SHELL': '/bin/bash', 17:47:49 INFO - 'SHLVL': '1', 17:47:49 INFO - 'TERM': 'linux', 17:47:49 INFO - 'TMOUT': '86400', 17:47:49 INFO - 'USER': 'cltbld', 17:47:49 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:49 INFO - '_': '/tools/buildbot/bin/python'} 17:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:47:51 INFO - Installing collected packages: mozsystemmonitor 17:47:51 INFO - Running setup.py install for mozsystemmonitor 17:47:51 INFO - Successfully installed mozsystemmonitor 17:47:51 INFO - Cleaning up... 17:47:51 INFO - Return code: 0 17:47:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:47:51 INFO - Using env: {'DISPLAY': ':0', 17:47:51 INFO - 'HOME': '/home/cltbld', 17:47:51 INFO - 'LANG': 'en_US.UTF-8', 17:47:51 INFO - 'LANGUAGE': 'en_US:en', 17:47:51 INFO - 'LOGNAME': 'cltbld', 17:47:51 INFO - 'MAIL': '/var/mail/cltbld', 17:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:51 INFO - 'MOZ_NO_REMOTE': '1', 17:47:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:51 INFO - 'NO_EM_RESTART': '1', 17:47:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:51 INFO - 'PWD': '/builds/slave/test', 17:47:51 INFO - 'SHELL': '/bin/bash', 17:47:51 INFO - 'SHLVL': '1', 17:47:51 INFO - 'TERM': 'linux', 17:47:51 INFO - 'TMOUT': '86400', 17:47:51 INFO - 'USER': 'cltbld', 17:47:51 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:51 INFO - '_': '/tools/buildbot/bin/python'} 17:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:51 INFO - Downloading/unpacking blobuploader==1.2.4 17:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:53 INFO - Downloading blobuploader-1.2.4.tar.gz 17:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:47:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:54 INFO - Downloading docopt-0.6.1.tar.gz 17:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:47:54 INFO - Installing collected packages: blobuploader, requests, docopt 17:47:54 INFO - Running setup.py install for blobuploader 17:47:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:47:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:47:54 INFO - Running setup.py install for requests 17:47:54 INFO - Running setup.py install for docopt 17:47:55 INFO - Successfully installed blobuploader requests docopt 17:47:55 INFO - Cleaning up... 17:47:55 INFO - Return code: 0 17:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:47:55 INFO - Using env: {'DISPLAY': ':0', 17:47:55 INFO - 'HOME': '/home/cltbld', 17:47:55 INFO - 'LANG': 'en_US.UTF-8', 17:47:55 INFO - 'LANGUAGE': 'en_US:en', 17:47:55 INFO - 'LOGNAME': 'cltbld', 17:47:55 INFO - 'MAIL': '/var/mail/cltbld', 17:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:55 INFO - 'MOZ_NO_REMOTE': '1', 17:47:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:55 INFO - 'NO_EM_RESTART': '1', 17:47:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:55 INFO - 'PWD': '/builds/slave/test', 17:47:55 INFO - 'SHELL': '/bin/bash', 17:47:55 INFO - 'SHLVL': '1', 17:47:55 INFO - 'TERM': 'linux', 17:47:55 INFO - 'TMOUT': '86400', 17:47:55 INFO - 'USER': 'cltbld', 17:47:55 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:55 INFO - '_': '/tools/buildbot/bin/python'} 17:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:47:55 INFO - Running setup.py (path:/tmp/pip-8Zjs_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:47:55 INFO - Running setup.py (path:/tmp/pip-8rZJEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:47:55 INFO - Running setup.py (path:/tmp/pip-Xe6RRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:47:55 INFO - Running setup.py (path:/tmp/pip-XQPdw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:47:55 INFO - Running setup.py (path:/tmp/pip-OKJfI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:47:55 INFO - Running setup.py (path:/tmp/pip-yuAQoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:47:55 INFO - Running setup.py (path:/tmp/pip-ZhkA0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:47:55 INFO - Running setup.py (path:/tmp/pip-Hw_TUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:47:55 INFO - Running setup.py (path:/tmp/pip-8_v8sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:47:55 INFO - Running setup.py (path:/tmp/pip-GU1Xhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:47:56 INFO - Running setup.py (path:/tmp/pip-VjAOIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:47:56 INFO - Running setup.py (path:/tmp/pip-ohX2sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:47:56 INFO - Running setup.py (path:/tmp/pip-t3jsY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:47:56 INFO - Running setup.py (path:/tmp/pip-zr48Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:56 INFO - Running setup.py (path:/tmp/pip-jgIBXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:47:56 INFO - Running setup.py (path:/tmp/pip-lIRP2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:47:56 INFO - Running setup.py (path:/tmp/pip-gnlE9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:47:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:47:56 INFO - Running setup.py install for manifestparser 17:47:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:47:56 INFO - Running setup.py install for mozcrash 17:47:56 INFO - Running setup.py install for mozdebug 17:47:56 INFO - Running setup.py install for mozdevice 17:47:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:47:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:47:56 INFO - Running setup.py install for mozfile 17:47:57 INFO - Running setup.py install for mozhttpd 17:47:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for mozinfo 17:47:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for mozInstall 17:47:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for mozleak 17:47:57 INFO - Running setup.py install for mozlog 17:47:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for moznetwork 17:47:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for mozprocess 17:47:57 INFO - Running setup.py install for mozprofile 17:47:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:47:57 INFO - Running setup.py install for mozrunner 17:47:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:47:58 INFO - Running setup.py install for mozscreenshot 17:47:58 INFO - Running setup.py install for moztest 17:47:58 INFO - Running setup.py install for mozversion 17:47:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:47:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:47:58 INFO - Cleaning up... 17:47:58 INFO - Return code: 0 17:47:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:47:58 INFO - Using env: {'DISPLAY': ':0', 17:47:58 INFO - 'HOME': '/home/cltbld', 17:47:58 INFO - 'LANG': 'en_US.UTF-8', 17:47:58 INFO - 'LANGUAGE': 'en_US:en', 17:47:58 INFO - 'LOGNAME': 'cltbld', 17:47:58 INFO - 'MAIL': '/var/mail/cltbld', 17:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:58 INFO - 'MOZ_NO_REMOTE': '1', 17:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:58 INFO - 'NO_EM_RESTART': '1', 17:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:58 INFO - 'PWD': '/builds/slave/test', 17:47:58 INFO - 'SHELL': '/bin/bash', 17:47:58 INFO - 'SHLVL': '1', 17:47:58 INFO - 'TERM': 'linux', 17:47:58 INFO - 'TMOUT': '86400', 17:47:58 INFO - 'USER': 'cltbld', 17:47:58 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:58 INFO - '_': '/tools/buildbot/bin/python'} 17:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:47:58 INFO - Running setup.py (path:/tmp/pip-PaGn9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:47:58 INFO - Running setup.py (path:/tmp/pip-K6gk0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:47:58 INFO - Running setup.py (path:/tmp/pip-p1i_RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:47:58 INFO - Running setup.py (path:/tmp/pip-aSEe6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:47:58 INFO - Running setup.py (path:/tmp/pip-dVtDQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:47:58 INFO - Running setup.py (path:/tmp/pip-5w2_a0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:47:58 INFO - Running setup.py (path:/tmp/pip-lFY89E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:47:59 INFO - Running setup.py (path:/tmp/pip-3rBuOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:47:59 INFO - Running setup.py (path:/tmp/pip-rSVpp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:47:59 INFO - Running setup.py (path:/tmp/pip-wLy7oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:47:59 INFO - Running setup.py (path:/tmp/pip-Iuc6JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:47:59 INFO - Running setup.py (path:/tmp/pip-Bgx30r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:47:59 INFO - Running setup.py (path:/tmp/pip-z635eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:47:59 INFO - Running setup.py (path:/tmp/pip-nTgpNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:59 INFO - Running setup.py (path:/tmp/pip-QBOEUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:47:59 INFO - Running setup.py (path:/tmp/pip-7kkPlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:47:59 INFO - Running setup.py (path:/tmp/pip-qRdwMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:47:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:01 INFO - Downloading blessings-1.6.tar.gz 17:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:48:01 INFO - Installing collected packages: blessings 17:48:01 INFO - Running setup.py install for blessings 17:48:01 INFO - Successfully installed blessings 17:48:01 INFO - Cleaning up... 17:48:01 INFO - Return code: 0 17:48:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:02 INFO - Reading from file tmpfile_stdout 17:48:02 INFO - Current package versions: 17:48:02 INFO - argparse == 1.2.1 17:48:02 INFO - blessings == 1.6 17:48:02 INFO - blobuploader == 1.2.4 17:48:02 INFO - docopt == 0.6.1 17:48:02 INFO - manifestparser == 1.1 17:48:02 INFO - mozInstall == 1.12 17:48:02 INFO - mozcrash == 0.17 17:48:02 INFO - mozdebug == 0.1 17:48:02 INFO - mozdevice == 0.48 17:48:02 INFO - mozfile == 1.2 17:48:02 INFO - mozhttpd == 0.7 17:48:02 INFO - mozinfo == 0.9 17:48:02 INFO - mozleak == 0.1 17:48:02 INFO - mozlog == 3.2 17:48:02 INFO - moznetwork == 0.27 17:48:02 INFO - mozprocess == 0.22 17:48:02 INFO - mozprofile == 0.28 17:48:02 INFO - mozrunner == 6.11 17:48:02 INFO - mozscreenshot == 0.1 17:48:02 INFO - mozsystemmonitor == 0.3 17:48:02 INFO - moztest == 0.7 17:48:02 INFO - mozversion == 1.4 17:48:02 INFO - psutil == 3.1.1 17:48:02 INFO - requests == 1.2.3 17:48:02 INFO - wsgiref == 0.1.2 17:48:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:48:02 INFO - Using env: {'DISPLAY': ':0', 17:48:02 INFO - 'HOME': '/home/cltbld', 17:48:02 INFO - 'LANG': 'en_US.UTF-8', 17:48:02 INFO - 'LANGUAGE': 'en_US:en', 17:48:02 INFO - 'LOGNAME': 'cltbld', 17:48:02 INFO - 'MAIL': '/var/mail/cltbld', 17:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:02 INFO - 'MOZ_NO_REMOTE': '1', 17:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:02 INFO - 'NO_EM_RESTART': '1', 17:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:02 INFO - 'PWD': '/builds/slave/test', 17:48:02 INFO - 'SHELL': '/bin/bash', 17:48:02 INFO - 'SHLVL': '1', 17:48:02 INFO - 'TERM': 'linux', 17:48:02 INFO - 'TMOUT': '86400', 17:48:02 INFO - 'USER': 'cltbld', 17:48:02 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:02 INFO - '_': '/tools/buildbot/bin/python'} 17:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:48:02 INFO - Cleaning up... 17:48:02 INFO - Return code: 0 17:48:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:48:02 INFO - Using env: {'DISPLAY': ':0', 17:48:02 INFO - 'HOME': '/home/cltbld', 17:48:02 INFO - 'LANG': 'en_US.UTF-8', 17:48:02 INFO - 'LANGUAGE': 'en_US:en', 17:48:02 INFO - 'LOGNAME': 'cltbld', 17:48:02 INFO - 'MAIL': '/var/mail/cltbld', 17:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:02 INFO - 'MOZ_NO_REMOTE': '1', 17:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:02 INFO - 'NO_EM_RESTART': '1', 17:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:02 INFO - 'PWD': '/builds/slave/test', 17:48:02 INFO - 'SHELL': '/bin/bash', 17:48:02 INFO - 'SHLVL': '1', 17:48:02 INFO - 'TERM': 'linux', 17:48:02 INFO - 'TMOUT': '86400', 17:48:02 INFO - 'USER': 'cltbld', 17:48:02 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:02 INFO - '_': '/tools/buildbot/bin/python'} 17:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:02 INFO - Downloading/unpacking jsonschema 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:48:04 INFO - Downloading/unpacking functools32 (from jsonschema) 17:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:48:04 INFO - warning: no files found matching '*.txt' 17:48:04 INFO - no previously-included directories found matching 'build' 17:48:04 INFO - no previously-included directories found matching 'dist' 17:48:04 INFO - no previously-included directories found matching '.git*' 17:48:04 INFO - Installing collected packages: jsonschema, functools32 17:48:04 INFO - Running setup.py install for functools32 17:48:04 INFO - warning: no files found matching '*.txt' 17:48:04 INFO - no previously-included directories found matching 'build' 17:48:04 INFO - no previously-included directories found matching 'dist' 17:48:04 INFO - no previously-included directories found matching '.git*' 17:48:04 INFO - Successfully installed jsonschema functools32 17:48:04 INFO - Cleaning up... 17:48:04 INFO - Return code: 0 17:48:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2277cf5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:48:04 INFO - Using env: {'DISPLAY': ':0', 17:48:04 INFO - 'HOME': '/home/cltbld', 17:48:04 INFO - 'LANG': 'en_US.UTF-8', 17:48:04 INFO - 'LANGUAGE': 'en_US:en', 17:48:04 INFO - 'LOGNAME': 'cltbld', 17:48:04 INFO - 'MAIL': '/var/mail/cltbld', 17:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:04 INFO - 'MOZ_NO_REMOTE': '1', 17:48:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:04 INFO - 'NO_EM_RESTART': '1', 17:48:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:04 INFO - 'PWD': '/builds/slave/test', 17:48:04 INFO - 'SHELL': '/bin/bash', 17:48:04 INFO - 'SHLVL': '1', 17:48:04 INFO - 'TERM': 'linux', 17:48:04 INFO - 'TMOUT': '86400', 17:48:04 INFO - 'USER': 'cltbld', 17:48:04 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:04 INFO - '_': '/tools/buildbot/bin/python'} 17:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:05 INFO - Downloading/unpacking flake8 17:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:48:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:48:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:48:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:48:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:48:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:48:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:48:07 INFO - no previously-included directories found matching 'docs/_build' 17:48:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:48:08 INFO - Running setup.py install for pep8 17:48:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:48:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:48:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:48:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:48:08 INFO - no previously-included directories found matching 'docs/_build' 17:48:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:48:08 INFO - Cleaning up... 17:48:08 INFO - Return code: 0 17:48:08 INFO - Running post-action listener: _resource_record_post_action 17:48:08 INFO - Running post-action listener: _start_resource_monitoring 17:48:08 INFO - Starting resource monitoring. 17:48:08 INFO - ##### 17:48:08 INFO - ##### Finished create-virtualenv step (success) 17:48:08 INFO - ##### 17:48:08 INFO - ##### 17:48:08 INFO - ##### Running install step. 17:48:08 INFO - ##### 17:48:08 INFO - Running pre-action listener: _resource_record_pre_action 17:48:08 INFO - Running main action method: install 17:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:08 INFO - Reading from file tmpfile_stdout 17:48:08 INFO - Detecting whether we're running mozinstall >=1.0... 17:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:48:08 INFO - Reading from file tmpfile_stdout 17:48:08 INFO - Output received: 17:48:08 INFO - Usage: mozinstall [options] installer 17:48:08 INFO - Options: 17:48:08 INFO - -h, --help show this help message and exit 17:48:08 INFO - -d DEST, --destination=DEST 17:48:08 INFO - Directory to install application into. [default: 17:48:08 INFO - "/builds/slave/test"] 17:48:08 INFO - --app=APP Application being installed. [default: firefox] 17:48:08 INFO - mkdir: /builds/slave/test/build/application 17:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:48:24 INFO - Reading from file tmpfile_stdout 17:48:24 INFO - Output received: 17:48:24 INFO - /builds/slave/test/build/application/firefox/firefox 17:48:24 INFO - Running post-action listener: _resource_record_post_action 17:48:24 INFO - ##### 17:48:24 INFO - ##### Finished install step (success) 17:48:24 INFO - ##### 17:48:24 INFO - ##### 17:48:24 INFO - ##### Running run-tests step. 17:48:24 INFO - ##### 17:48:24 INFO - Running pre-action listener: _resource_record_pre_action 17:48:24 INFO - Running main action method: run_tests 17:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:48:24 INFO - Python 2.7.3 17:48:24 INFO - Return code: 0 17:48:24 INFO - grabbing minidump binary from tooltool 17:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:48:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:48:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:48:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:48:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:48:24 INFO - Return code: 0 17:48:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:48:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:48:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:48:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:48:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:48:25 INFO - Return code: 0 17:48:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:48:25 INFO - Using env: {'DISPLAY': ':0', 17:48:25 INFO - 'HOME': '/home/cltbld', 17:48:25 INFO - 'LANG': 'en_US.UTF-8', 17:48:25 INFO - 'LANGUAGE': 'en_US:en', 17:48:25 INFO - 'LOGNAME': 'cltbld', 17:48:25 INFO - 'MAIL': '/var/mail/cltbld', 17:48:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:48:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:25 INFO - 'MOZ_NO_REMOTE': '1', 17:48:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:48:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:25 INFO - 'NO_EM_RESTART': '1', 17:48:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:25 INFO - 'PWD': '/builds/slave/test', 17:48:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:48:25 INFO - 'SHELL': '/bin/bash', 17:48:25 INFO - 'SHLVL': '1', 17:48:25 INFO - 'TERM': 'linux', 17:48:25 INFO - 'TMOUT': '86400', 17:48:25 INFO - 'USER': 'cltbld', 17:48:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990', 17:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:25 INFO - '_': '/tools/buildbot/bin/python'} 17:48:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:48:25 INFO - mozversion application_buildid: 20160607155851 17:48:25 INFO - mozversion application_changeset: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d 17:48:25 INFO - mozversion application_display_name: Nightly 17:48:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:48:25 INFO - mozversion application_name: Firefox 17:48:25 INFO - mozversion application_remotingname: firefox 17:48:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 17:48:25 INFO - mozversion application_vendor: Mozilla 17:48:25 INFO - mozversion application_version: 49.0a1 17:48:25 INFO - mozversion platform_buildid: 20160607155851 17:48:25 INFO - mozversion platform_changeset: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d 17:48:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 17:48:25 INFO - mozversion platform_version: 49.0a1 17:48:25 INFO - using testdate: 1465346905 17:48:25 INFO - actual date: 1465346905 17:48:25 INFO - starting webserver on 'localhost:41118' 17:48:25 INFO - SUITE-START | Running 2 tests 17:48:25 INFO - TEST-START | dromaeo_css 17:48:25 INFO - operating with platform_type : linux_ 17:48:25 INFO - Initialising browser for dromaeo_css test... 17:48:25 INFO - TEST-INFO | started process 20414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppvpih7/profile http://localhost:41118/getInfo.html) 17:48:30 INFO - PROCESS | 20414 | __metrics Screen width/height:1600/1200 17:48:30 INFO - PROCESS | 20414 | colorDepth:24 17:48:30 INFO - PROCESS | 20414 | Browser inner width/height: 1024/697 17:48:30 INFO - PROCESS | 20414 | __metrics 17:48:31 ERROR - PROCESS | 20414 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:48:31 INFO - TEST-INFO | 20414: exit 0 17:48:31 INFO - Browser initialized. 17:48:31 INFO - Running cycle 1/1 for dromaeo_css test... 17:48:31 INFO - TEST-INFO | started process 20495 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppvpih7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:48:43 INFO - PROCESS | 20495 | RSS: Main: 156553216 17:48:43 INFO - PROCESS | 20495 | 17:49:35 INFO - PROCESS | 20495 | 1465346975137 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:51:46 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41118/tests/dromaeo/cssquery-ext.html) 17:51:47 INFO - PROCESS | 20495 | RSS: Main: 261046272 17:51:47 INFO - PROCESS | 20495 | 17:54:50 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-ext.html (next: http://localhost:41118/tests/dromaeo/cssquery-jquery.html) 17:54:50 INFO - PROCESS | 20495 | RSS: Main: 241213440 17:54:50 INFO - PROCESS | 20495 | 17:57:52 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41118/tests/dromaeo/cssquery-mootools.html) 17:57:53 INFO - PROCESS | 20495 | RSS: Main: 241709056 17:57:53 INFO - PROCESS | 20495 | 18:00:55 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41118/tests/dromaeo/cssquery-prototype.html) 18:00:56 INFO - PROCESS | 20495 | RSS: Main: 243130368 18:00:56 INFO - PROCESS | 20495 | 18:03:59 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41118/tests/dromaeo/cssquery-yui.html) 18:03:59 INFO - PROCESS | 20495 | RSS: Main: 207519744 18:03:59 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | Cycle 1(1): loaded http://localhost:41118/tests/dromaeo/cssquery-yui.html (next: http://localhost:41118/tests/dromaeo/cssquery-dojo.html) 18:07:02 INFO - PROCESS | 20495 | RSS: Main: 273137664 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | __start_tp_report 18:07:02 INFO - PROCESS | 20495 | _x_x_mozilla_page_load 18:07:02 INFO - PROCESS | 20495 | _x_x_mozilla_page_load_details 18:07:02 INFO - PROCESS | 20495 | |i|pagename|runs| 18:07:02 INFO - PROCESS | 20495 | |0;dojo.html;2644.7223041580633;2663.06792622582;2687.8252913560623;2698.2579790557593;2701.959470607941;4635.188841952653;4644.280136578827;4647.465541462733;4718.197906355919;4753.47711751707;4644.489106198315;4687.624990000801;4722.3860898083285;4742.05158968206;4769.833055843046;4296.63478604319;4310.03024319528;4312.676549258803;4337.522872484024;4342.761148136851;113.08894630297635;4384.660672767001;4428.7121337112985;4432.246518091933;4457.955420445786;4289.785510724467;4362.062156636307;4401.603855652975;4407.2948328267485;4410.316400957825;4355;4364.803583838735;4365.890852728676;4407.867763967054;4411.955880441186;4248.4052232687445;4327.091310824699;4334.219840428722;4342.978285108586;4389.473263208435;4574;4614.215583350839;4615.030843522864;4617.861464156081;4625.884352891171;4955.430125535538;4958.727270000149;4992.176290911995;5064.645474816764;5086.491350864921;5015.247712843103;5060.645754797166;5070.112730272188;5070.518300761421;5073.315102461157;4600.60894823937;4695.483496815347;4710.387649605598;4713.6936099153445;4721.150192965265;5141.177411614199;5189.55888749453;5194;5260.13207820713;5260.605454590921;4873.293372460973;4919.385076865392;4921.212605983025;4928.950710492849;4968.677035992649;4967.726775027336;5020;5075.086484432837;5089.05852417304;5137.563307118937;4985.9750701246985;4994.825181118644;5014.52362025607;5021.899562008739;5053.1157047516535;4994.825181118644;5005.674631148963;5012.223105418593;5014.724190169502;5049.318342023853;4128.855490057834;4166.416701661769;4168.874933751992;4174.91650167001;4220.2192594370135;3666.046827824785;3682.024263570156;3693.796841173761;3710.573284072312;3772.43413487979;4372.3878656988045;4374.934375984363;4383.561643835591;4410.911781764346;4423.778811059498;4914.164592019365;5093.439721630618;5102.979404119191;5110.131277682803;5110.463401342806;4324.589164029412;4324.935125973113;4348.043430445297;4352.9988102735915;4357.085012147421;4477.7984990675495;4487.282034874404;4508.842190523316;4525.0497395546645;4550.044490656999;4722.52774722525;4788.473267940454;4795.352627395327;4801.4958429364415;4803.087413391375;5086.949130508648;5095.872603184802;5161.483851614808;5167.638265321391;5173.870653233699;4828.275758636092;4828.855134345975;4830.541098595628;4832.758362081952;4875.975620121877;4857.902841943071;5029.321041659329;5029.59763218949;5033.823816166417;5082.796688132434;5018.548330650259;5046.9747651261505;5067.645264831426;5076.923846142385;5080.034793389244;4927.408710954708;4929.088118698052;4934.925976110433;4938.629602779878;4984.352034235526;4773.928391074067;4857.6599638025;4919.532644398776;4937.5062493751775;4994.32576602154;4800.3279540865005;4822.807087716452;4841.370621819141;4869;4888.7311197884455;4912.582430493448;4934.7285899275885;4941.382327209099;4943.431505376784;4946.258061290835;4763.30932014849;4811.422629284508;4824.638152138534;4878.243872199815;4923.950760492349 18:07:02 INFO - PROCESS | 20495 | |1;ext.html;553.1232995701812;596.6390333848025;602.5089552015107;602.6896148483529;629.1003864473804;27518.312042198955;39535.80232098839;40201.39697904526;40279.59720402795;40306.798466007705;42485;43090.7845460773;43108.56891431085;43192.352114718306;43237.13525729484;7500.549967001997;7734.651940662684;7912.129665736765;7917.287444130027;10042.096211340979;9672.22622190223;10064.094231519162;10073.294869359148;10086.445245511493;10086.798264034715;10080.798384032352;10154.89845101547;10155.949220253888;10159.644412445567;10160.33957792741;7574.734884279023;7810.687572497093;7811.062672479282;10023;10031.448270345129;9780.021997800162;10173.186145108373;10180.949095254551;10181.592736290539;10186.847197292047;38346.42480362769;38706.41940370897;38708.61291387107;38779.22441551153;38806.80596597027;2947.3957838643028;2975.880964761407;2989.0733872499595;3100.4419204543174;3434.9180008297308;28782.136535903956;29429.411411771645;29449.263768405748;30488.54267185994;31868;3327.085051610826;3344.8160351180677;3398.2693720850134;3413.1808365991983;3452.378571857065;254355;255938;256550.71724641445;257015;258043;31974;56785;57242.7137864308;57324.71337643368;57333.71333143308;32712.836435818146;34289;34334.82832585821;34362.31275374479;34382.140446488535;13286.66783330405;13467.528636497871;13482.595522134352;13483.595492135251;13493.865061349261;9936;11369.090472762324;11512.194146409838;11631.53473861036;11833.822492662617;7952.648049831542;7958;7985.480943738638;8023.277904988579;8035.9508325376655;3101.4727496325677;3121.9219519512194;3122.937541249162;3169.5245713142754;3200.6639302873327;818.1940788323496;847.2713466418875;933.8793447862593;959.5058544849409;1053.2837670384213;26798.330041748723;28519.32895696567;28729.56905646417;28738.13785586436;28990.27524311909;879.7404765594165;892.2817132208635;896.7399454158351;936.4755736787421;1037.8131936251548;14907.925460372846;14908.925455372872;15167.620809479631;15410.845891541165;15486.22568871552;394824;398190;398516;398916;399321;50503.74748126309;51109.23336149961;51158.23262651063;51184.23223651648;51272;1068.6046162919613;1141.49774099396;1167.8073117935614;1172.4841069929205;1192.5170306026073;28329.71670283271;28915.7108428913;29005.38413129699;29336.413271734873;29520.852395737882;43154.136917262105;43338.349924750524;43687.78156109326;43716.56283437125;43762.56237437585;306032;307430;307947;308304;308857;21300.147994080682;24300.392490187885;25343;25394.619080714172;25468.617970730826;41641.334346625794;41919.371209432495;42060.57939420567;42540.57459425366;42781;36592.81703591465;36676.63323366839;36793.448098278015;37066;37194.442083369315;35550.46674299836;36384.45423318599;36847;36880.26239475249;37171.256574868894;31868;33294;36326;36506.452403214505;36638.8168059158;31947.36105277928;32696.50955235721;32882.75227706728;33081.50377744384;33544.83227583944;24989.500209995334;25128.24615261472;25222.117225897007;25596.744032559436;25662.743372566034 18:07:02 INFO - PROCESS | 20495 | |2;jquery.html;345.5783943588822;354.7445838995922;356.23942881947045;366.0756589611233;372.7688832923588;15836.524904252876;16880.155992200373;17016.829831701678;17340.653186936255;17631;16257;16415.425460108898;16623.251953662086;16802.579935501617;16855.578610534743;4935.728534930578;5141.845744627667;5181.248718935752;5186.766595503212;5191.584673226152;3701.130234394915;3864.0339915021245;4976.0794253063295;5692.487676109149;6024.2168518092585;6034.728437220314;6053.848653783647;6095;6111.511079113682;6111.511079113682;5891.23413956183;5959.553033522481;6050.092486127072;6054.485368743662;6082.665453400084;3358.177246574605;4852.757362131914;5122.410922743895;5127.461616530248;5228.5817134629515;20038.398848034583;20745.377638670863;20773.792262077488;20829.479263018547;20880.164793408247;1920.3374835681668;2159.8164156046755;2241.1371621925514;2383.3088404362716;2464.827462077655;12651.810222846665;13156.473741050346;13228.338583070905;13646.385912633956;13716.794248086288;1694.1952572528073;1755.4909076367837;1766.3023105873237;1775.520609435458;1804.1790985101748;61256.387436125515;61897.690511547604;62025.27848329115;62522.68738656323;63348.46606135749;8494.660213591387;8813.735587932373;8912.8663070054;8940.418872773249;9035.186833185046;12817.1668841525;12945.611631650878;12988.545400910923;13018.934905325603;13026.088173827931;7060.3286291128325;7194.38847697941;7210.819729506804;7247.166575843846;7291.4531410144455;7226.6386680665755;7245.239249878687;7401.148867880155;7414.406847452191;7465.70137194517;5784.913226301609;5854.882902341929;5920.644761314335;5950.9107363389585;6008.66952317618;1501.8122734658168;1552.5109590478962;1554.6735185611149;1670.9081000545086;1702.1234064456814;822.880682301063;860.0066922704286;946.990530094704;948.1892981500717;977.1694060049043;16417.83582164163;16931.830681693027;17441.12794360277;17615.383461578782;17818.821811781716;830.4975489828687;834.0366876257932;844.3287586368851;864.1401805203863;895.5746020640144;7987.281144696889;9585.185259252901;9617.71146865581;9924.610554522373;10135.797284054424;362586;364788;376914;377901;383341;51024.23463648048;56873;58400.415995839496;62675.37324626695;63270;929.3819609959437;945.6737425588161;954.8663187153666;957.1577012229194;961.2214106573558;15655.765163522328;16089.356425743308;16207.75688364699;16274.16415782;16317.510474685778;32295.838520807247;32448.513272301407;33154.834225828716;33830.15424614404;33907.83046084754;40725;43188.784056079516;43650.34524482072;43717.78141109274;43810.34284485799;6990.510664253454;7078.362947334765;7217.925837794922;7327.194008659149;7358.080239970004;10923.836142458029;11246.100311975186;11300.078986572467;11358.829617555908;11526.59656912004;9064.954675226581;9479.952600237231;9657.372270802376;9774.902250977684;9896.653617123366;9588.11479485297;9821.36161149523;9901.405915645084;9947.452890091114;10008;9071;9832.115109640166;9847.409155450696;10085.899141008496;10116.19070474375;7016.684249208696;10383.94808025955;10430.165586753194;10624.468776560987;10748.570057197938;9310.72067837966;9811;10169.692771978489;10209.285350025428;10283.794324113325 18:07:02 INFO - PROCESS | 20495 | |3;mootools.html;1246.52008976544;1410.464023671005;1435.146088077594;1537.2390666620017;1714.1343621471156;6746.460283177346;7544.4718869679145;7724.1889601591865;7753.806154846138;7935.52386856789;7656;7829.843403131934;7830.765077047698;7838.64726087326;7844.058712954439;1883.1525813384033;1930.054273406028;1931.5943651833131;2157.751858536265;2200.119952019197;1984.5534754680154;2163.902624381899;2199.9560008799895;2211.137656314039;2226.8107210887015;2172.0768673313855;2180.858244214121;2193.5722534105857;2195.121951219517;2197.2419515267206;1835.3392778599693;2127.2554605887894;2134.103676455892;2135.2099723102397;2136.9893150534303;2162.048698572639;2213.0594497338566;2213.4798322394226;2233.653783663535;2236.1726161320253;7711.26742959418;7739.071311442663;7745.419093567949;7761.805954851117;7767.223277672244;4542.500324964251;4640.280756482751;4642.9535704643195;4644.698094623873;4651.232546629802;2810.409813939055;2866.7993240473174;2874.4107457971277;2881.919280269899;2883.2935930696904;843.2579330189456;873.9825203495894;878.2578720980761;880.5377176982136;882.0914458108159;10250.128739057189;10279.79440411195;10288;10292.279540432173;10324.483775811255;4063.674906007512;4150.730202536825;4241.97879010603;4242.893927651833;4275.358696195595;8677.956610217036;8758.605862736193;8761.34289928258;8763.211310982042;8766.517841518651;4629.259318509023;4672.182368085571;4674.462436819741;4680.485146633868;4684.672072954861;3672.8530858765885;3773.132179598708;4217.8523751668545;4272.060146767706;4273.55127711592;3650.5071815305123;3749.775013499199;3762.3415902217002;3793.3740932745786;3796.468494410785;1174.3130268792852;1174.94712737927;1187.4062968515743;1192.0582739635763;1199.3343694249775;646.8706258748269;692.4149094015548;798.1499702816482;804.7545498622976;827.9710210142616;5587.3015873015875;5597.020521408737;5600.299962504687;5619.719014049363;5697.344805347355;874.2525141004477;927.4852456886492;941.3128416256171;969.7284760267137;985.8274801909637;3379.1552111972005;3394.5077963695126;3409.7783644063056;3496.4580490024086;3584.9103772405897;9367;9412;9649.565769540388;9657.661981830603;9660.37207581505;9452.054794520493;9512.667056652985;9515.666951656658;9531.094546018117;9534.332596718163;7281.854362912712;7376.7049318026675;7407.481476296715;7420.072490938633;7421.257874212643;4948.257761335828;4991.9500804991485;5105.387353517602;5123.5644970177955;5186.040582492251;8663.783405414915;8738.169873861973;8796.252318552739;8799.648014079365;8933.731988040368;8666.566671666265;8886.333524985523;9187.310951678517;9190.770230744285;9196.954015229881;6287.779927702509;6299.212598425197;6414.294427613052;6439.162908822011;6439.935600643934;7355.411567074514;7385.150707668687;7424.6287685614425;7434.73978410753;7435.256474352522;6673.8998915015345;6833.2483426824;6839.692213850488;6933.5493192942295;6958.165020197609;6572.868542629216;6625.271220165874;6695.330466953265;6745.190577130579;6848.623325716934;6074.149619053248;6218.81343559694;6508.511861610493;6519.478441724746;6534.150560427114;4584.88537786558;4595.494495605414;4642.16441040616;4674.953250467452;4752.42970843501;4625.930611040904;4683.648726345469;4722.85831425044;4753.786079626356;4782.856514304576 18:07:02 INFO - PROCESS | 20495 | |4;prototype.html;286.535811984585;292.6809777342696;295.426871868575;298.40914988323135;298.70875896001417;4756.524347565246;4790.904181916364;4814.205656066753;4824.204006338954;4837.540433658805;4770.976145119278;4772.570468657829;4783.856484305467;4787.085165933629;4798.760061996895;3569.4824250483666;3667.193217492161;3669.6880765134997;3679.319325924699;3688.8155592220414;3649.3431182387158;3690.5940346561856;3757.718171137162;3785.4889589905424;3823.655870971612;3688.79711615861;3730.608286129958;3731.891481313063;3736.775793452388;3752.3433399155037;3559.148456168665;3735.62643735627;3739.551253849542;3752.737308388414;3760.078780698719;3133.905982820519;3190.978886756239;3307.9007629771145;3315.784474009182;3325.551050608161;4554.772261386917;4584.33527138563;4587.288970209623;4589.977050114761;4592.724436533818;4231.555686652888;4240.342746874239;4242.4272723182285;4264.125854199881;4291.420658211133;4898.853034408973;4962.528559786814;4967.304577359173;4976.35307410038;5024.899502009939;3545.911352216215;3562.5240457881782;3563.465480177968;3565.3760591896316;3577.159367548624;5234.42421333653;5244.187150991603;5255.894882102375;5270.446603106657;5285.7885684572575;3605.4772058051435;3683.6684698377276;3701.0747313171705;3708.8145592720257;3721.832517536717;3685.576158741779;3692.9446058309145;3716.5168528091176;3723.385641369198;3729.8180564370145;4211.389348544505;4211.663066954698;4214.178235244098;4260.616544511009;4266.4240327556;4985.626078044161;5024.748762561857;5034.320366750515;5036.546710796046;5049.924251136236;1206.8551773787026;1270.0347735720918;1270.4854533913779;1289.071868254855;1315.0860152194134;780.2314720000908;784.3489903380181;787.9842403151905;797.4776521378507;804.9758507244792;3974.682025437958;3979.6816254699556;4003.179348233605;4022.2960981828646;4046.3728122141115;4606.917374417009;4619.491855895849;4623.56076172763;4640.69835460694;4662.181749117847;3997.700172487075;4002.3196056670436;4036.6972477064337;4044.5348784889525;4071.6742660587083;2373.1693907132712;2465.9136930207355;2469.4196863737006;2477.715062767769;2669.4394177223003;4831.227003679394;4949.7772600233075;4956.430010548761;4956.801727930914;4962.950370496321;5310.203469479532;5324.946750532523;5343.492368225013;5347.1979203119845;5367.228814491208;4738.763061846894;4931.876703082452;4931.914978704679;4933.827316043921;4938.481459446706;5095.388553373471;5098.898022039612;5164.922526162036;5193.195054766517;5204.427512973645;5099.643024988215;5143.5113664201845;5146.485351464824;5164.767585458589;5170.870728231674;4749.928751068667;4960.900781984412;4976.328195693535;4982.131700612271;4982.177940639754;3577.3203091412547;3580.373434648874;3597.496350510878;3604.2250916052126;3607.8917632471066;5188.766505507186;5206.6095042871175;5234.842954711365;5237.2667826505085;5249.658772179796;4770.260609605517;5053.59571234293;5054.974725126351;5063.7468126592785;5084.440711521655;5050.722210278323;5067.949320506747;5070.721110338966;5078.806480477011;5101.183810590288;4985.675931064469;4999.675021123615;5006.799728010838;5008.974955125201;5010.974945125251;4876.472635084726;5058.3929928408825;5082.466341034138;5100.107481190851;5118.43697193301;4985.401751789664;4996.625253105959;5001.24981252815;5009.198528235611;5014.824481143142 18:07:02 INFO - PROCESS | 20495 | |5;yui.html;639.3446717114956;696.2237105627221;711.0898050495367;729.4747781597251;773.6286582440426;11716.707082322946;12430.751384972295;12432.875671243284;12452.31512266825;12461.190022648521;11312;11732.472038758255;11771.941140294286;11782.58760943365;11800.940995295034;777.1878387085509;794.1661255681511;794.9880751788728;795.5028107432854;795.8090058386003;624.1636207481973;771.6797529025448;777.5917643237317;785.8428314337127;792.4611264340247;807.2331285278979;807.4630370803409;807.4953154278576;807.6809660184224;807.8424707182102;652.5888690125191;802.9598520074002;803.317180396664;803.8151225218203;805.8066064144592;594.0524862843757;594.378874076591;595.3391735173954;613.1171113596452;614.2966303582418;10698;10756.515956781963;10779.461026948622;10780.838287425695;10793.136549076055;11811.763764724743;12285.69285767854;12581.811272830915;12655.557055503014;12674.36628168588;10480.94759526205;10577.682669519927;10582.629607963761;10692;10898.673039808738;3992.480977472939;4002.5397079335667;4018.417329487237;4039.9596004039877;4107.116969851496;43801.56198438007;44089.338659920126;45512;45616.54383456156;45716.77141614271;9817.754556136011;9828.705138845846;9843.901560984299;9858.063483969012;10108.191344692406;17321.30714771395;17448.65102697964;17480.562985925455;17495.125243737763;17550.736738948926;6492.577982431127;6550.770723024767;6628.23775265841;6671.299513551105;6675.432588229958;3528.894133176009;3628.63713628635;3645.927081458356;3691.9815400922826;3743.8502459901842;2790.7627851632838;2885.7691384689174;2887.956680649792;2890.5375139977505;2891.79757416983;523.9869003274872;524.4912434938104;530.8938212357467;534.3934634190144;535.61703382082;464.7095565271705;473.7228721198069;476.94992025836785;477.7873846138435;506.8656805946427;6814.454843612499;6877;6931.861362772716;7011;7032.437405007587;502.5928997512027;506.83527853447777;514.9948500515022;526.5734754848509;530.541081964106;3288.852001659931;3414.1073303770986;3499.720022398202;3507.8421471033867;3574.303010912847;47220.29169562459;48818.75590622024;48952;54358.18462723062;55767;48731.756341218774;49600.75199624051;49853.25220121701;49865.50134498681;50423.74788126036;44580.3312950306;45029.77485112619;45115.77442112769;46863.765681171375;47408.28887566689;8028.6387112578905;8328.167183281623;8366;8371.916280837113;8473.745787626382;31472.370552588363;31978;32085.75399721997;32532.674673252965;33964.83017584896;39240.411393828545;39624.603753963245;40454.393184101675;43559.56440435555;45748;11082.722931926766;11382.260153090023;11516.654500365003;11659.417029148339;12060.75878482443;11509.769804604028;11692.70768230801;11861.703457413634;12008.939955300168;12223.633291001284;10461.110805581611;10587.682369528926;10720.463976801284;10934.890651093387;11050.392228427514;10159.492025398848;10425.687229383131;10714.51784669676;10777.568897244022;11085.224034317844;10194.694159175237;10436.37381757097;10548.472576370998;10576.36541807494;10791;9411.200047995997;9516.762080948032;9522.571484283362;9944.801103978025;10031.799364012824;9197;9366.81266374655;9479.146876781122;9499.47752873571;9557.52212389364 18:07:02 INFO - PROCESS | 20495 | __end_tp_report 18:07:02 INFO - PROCESS | 20495 | __start_cc_report 18:07:02 INFO - PROCESS | 20495 | _x_x_mozilla_cycle_collect,948 18:07:02 INFO - PROCESS | 20495 | __end_cc_report 18:07:02 INFO - PROCESS | 20495 | __startTimestamp1465348022817__endTimestamp 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | ------- Summary: start ------- 18:07:02 INFO - PROCESS | 20495 | Number of tests: 6 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#0] dojo.html Cycles:165 Average:4639.27 Median:4803.09 stddev:603.26 (12.6%) stddev-sans-first:584.47 18:07:02 INFO - PROCESS | 20495 | Values: 2644.7 2663.1 2687.8 2698.3 2702.0 4635.2 4644.3 4647.5 4718.2 4753.5 4644.5 4687.6 4722.4 4742.1 4769.8 4296.6 4310.0 4312.7 4337.5 4342.8 113.1 4384.7 4428.7 4432.2 4458.0 4289.8 4362.1 4401.6 4407.3 4410.3 4355.0 4364.8 4365.9 4407.9 4412.0 4248.4 4327.1 4334.2 4343.0 4389.5 4574.0 4614.2 4615.0 4617.9 4625.9 4955.4 4958.7 4992.2 5064.6 5086.5 5015.2 5060.6 5070.1 5070.5 5073.3 4600.6 4695.5 4710.4 4713.7 4721.2 5141.2 5189.6 5194.0 5260.1 5260.6 4873.3 4919.4 4921.2 4929.0 4968.7 4967.7 5020.0 5075.1 5089.1 5137.6 4986.0 4994.8 5014.5 5021.9 5053.1 4994.8 5005.7 5012.2 5014.7 5049.3 4128.9 4166.4 4168.9 4174.9 4220.2 3666.0 3682.0 3693.8 3710.6 3772.4 4372.4 4374.9 4383.6 4410.9 4423.8 4914.2 5093.4 5103.0 5110.1 5110.5 4324.6 4324.9 4348.0 4353.0 4357.1 4477.8 4487.3 4508.8 4525.0 4550.0 4722.5 4788.5 4795.4 4801.5 4803.1 5086.9 5095.9 5161.5 5167.6 5173.9 4828.3 4828.9 4830.5 4832.8 4876.0 4857.9 5029.3 5029.6 5033.8 5082.8 5018.5 5047.0 5067.6 5076.9 5080.0 4927.4 4929.1 4934.9 4938.6 4984.4 4773.9 4857.7 4919.5 4937.5 4994.3 4800.3 4822.8 4841.4 4869.0 4888.7 4912.6 4934.7 4941.4 4943.4 4946.3 4763.3 4811.4 4824.6 4878.2 4924.0 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#1] ext.html Cycles:180 Average:46962.97 Median:18319.41 stddev:85922.97 (469.0%) stddev-sans-first:86093.35 18:07:02 INFO - PROCESS | 20495 | Values: 553.1 596.6 602.5 602.7 629.1 27518.3 39535.8 40201.4 40279.6 40306.8 42485.0 43090.8 43108.6 43192.4 43237.1 7500.5 7734.7 7912.1 7917.3 10042.1 9672.2 10064.1 10073.3 10086.4 10086.8 10080.8 10154.9 10155.9 10159.6 10160.3 7574.7 7810.7 7811.1 10023.0 10031.4 9780.0 10173.2 10180.9 10181.6 10186.8 38346.4 38706.4 38708.6 38779.2 38806.8 2947.4 2975.9 2989.1 3100.4 3434.9 28782.1 29429.4 29449.3 30488.5 31868.0 3327.1 3344.8 3398.3 3413.2 3452.4 254355.0 255938.0 256550.7 257015.0 258043.0 31974.0 56785.0 57242.7 57324.7 57333.7 32712.8 34289.0 34334.8 34362.3 34382.1 13286.7 13467.5 13482.6 13483.6 13493.9 9936.0 11369.1 11512.2 11631.5 11833.8 7952.6 7958.0 7985.5 8023.3 8036.0 3101.5 3121.9 3122.9 3169.5 3200.7 818.2 847.3 933.9 959.5 1053.3 26798.3 28519.3 28729.6 28738.1 28990.3 879.7 892.3 896.7 936.5 1037.8 14907.9 14908.9 15167.6 15410.8 15486.2 394824.0 398190.0 398516.0 398916.0 399321.0 50503.7 51109.2 51158.2 51184.2 51272.0 1068.6 1141.5 1167.8 1172.5 1192.5 28329.7 28915.7 29005.4 29336.4 29520.9 43154.1 43338.3 43687.8 43716.6 43762.6 306032.0 307430.0 307947.0 308304.0 308857.0 21300.1 24300.4 25343.0 25394.6 25468.6 41641.3 41919.4 42060.6 42540.6 42781.0 36592.8 36676.6 36793.4 37066.0 37194.4 35550.5 36384.5 36847.0 36880.3 37171.3 31868.0 33294.0 36326.0 36506.5 36638.8 31947.4 32696.5 32882.8 33081.5 33544.8 24989.5 25128.2 25222.1 25596.7 25662.7 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#2] jquery.html Cycles:180 Average:22927.34 Median:4955.90 stddev:61094.92 (1232.8%) stddev-sans-first:61242.78 18:07:02 INFO - PROCESS | 20495 | Values: 345.6 354.7 356.2 366.1 372.8 15836.5 16880.2 17016.8 17340.7 17631.0 16257.0 16415.4 16623.3 16802.6 16855.6 4935.7 5141.8 5181.2 5186.8 5191.6 3701.1 3864.0 4976.1 5692.5 6024.2 6034.7 6053.8 6095.0 6111.5 6111.5 5891.2 5959.6 6050.1 6054.5 6082.7 3358.2 4852.8 5122.4 5127.5 5228.6 20038.4 20745.4 20773.8 20829.5 20880.2 1920.3 2159.8 2241.1 2383.3 2464.8 12651.8 13156.5 13228.3 13646.4 13716.8 1694.2 1755.5 1766.3 1775.5 1804.2 61256.4 61897.7 62025.3 62522.7 63348.5 8494.7 8813.7 8912.9 8940.4 9035.2 12817.2 12945.6 12988.5 13018.9 13026.1 7060.3 7194.4 7210.8 7247.2 7291.5 7226.6 7245.2 7401.1 7414.4 7465.7 5784.9 5854.9 5920.6 5950.9 6008.7 1501.8 1552.5 1554.7 1670.9 1702.1 822.9 860.0 947.0 948.2 977.2 16417.8 16931.8 17441.1 17615.4 17818.8 830.5 834.0 844.3 864.1 895.6 7987.3 9585.2 9617.7 9924.6 10135.8 362586.0 364788.0 376914.0 377901.0 383341.0 51024.2 56873.0 58400.4 62675.4 63270.0 929.4 945.7 954.9 957.2 961.2 15655.8 16089.4 16207.8 16274.2 16317.5 32295.8 32448.5 33154.8 33830.2 33907.8 40725.0 43188.8 43650.3 43717.8 43810.3 6990.5 7078.4 7217.9 7327.2 7358.1 10923.8 11246.1 11300.1 11358.8 11526.6 9065.0 9480.0 9657.4 9774.9 9896.7 9588.1 9821.4 9901.4 9947.5 10008.0 9071.0 9832.1 9847.4 10085.9 10116.2 7016.7 10383.9 10430.2 10624.5 10748.6 9310.7 9811.0 10169.7 10209.3 10283.8 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#3] mootools.html Cycles:180 Average:5099.76 Median:5592.16 stddev:2835.13 (50.7%) stddev-sans-first:2828.30 18:07:02 INFO - PROCESS | 20495 | Values: 1246.5 1410.5 1435.1 1537.2 1714.1 6746.5 7544.5 7724.2 7753.8 7935.5 7656.0 7829.8 7830.8 7838.6 7844.1 1883.2 1930.1 1931.6 2157.8 2200.1 1984.6 2163.9 2200.0 2211.1 2226.8 2172.1 2180.9 2193.6 2195.1 2197.2 1835.3 2127.3 2134.1 2135.2 2137.0 2162.0 2213.1 2213.5 2233.7 2236.2 7711.3 7739.1 7745.4 7761.8 7767.2 4542.5 4640.3 4643.0 4644.7 4651.2 2810.4 2866.8 2874.4 2881.9 2883.3 843.3 874.0 878.3 880.5 882.1 10250.1 10279.8 10288.0 10292.3 10324.5 4063.7 4150.7 4242.0 4242.9 4275.4 8678.0 8758.6 8761.3 8763.2 8766.5 4629.3 4672.2 4674.5 4680.5 4684.7 3672.9 3773.1 4217.9 4272.1 4273.6 3650.5 3749.8 3762.3 3793.4 3796.5 1174.3 1174.9 1187.4 1192.1 1199.3 646.9 692.4 798.1 804.8 828.0 5587.3 5597.0 5600.3 5619.7 5697.3 874.3 927.5 941.3 969.7 985.8 3379.2 3394.5 3409.8 3496.5 3584.9 9367.0 9412.0 9649.6 9657.7 9660.4 9452.1 9512.7 9515.7 9531.1 9534.3 7281.9 7376.7 7407.5 7420.1 7421.3 4948.3 4992.0 5105.4 5123.6 5186.0 8663.8 8738.2 8796.3 8799.6 8933.7 8666.6 8886.3 9187.3 9190.8 9197.0 6287.8 6299.2 6414.3 6439.2 6439.9 7355.4 7385.2 7424.6 7434.7 7435.3 6673.9 6833.2 6839.7 6933.5 6958.2 6572.9 6625.3 6695.3 6745.2 6848.6 6074.1 6218.8 6508.5 6519.5 6534.2 4584.9 4595.5 4642.2 4675.0 4752.4 4625.9 4683.6 4722.9 4753.8 4782.9 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#4] prototype.html Cycles:180 Average:4133.44 Median:4744.35 stddev:1228.72 (25.9%) stddev-sans-first:1197.77 18:07:02 INFO - PROCESS | 20495 | Values: 286.5 292.7 295.4 298.4 298.7 4756.5 4790.9 4814.2 4824.2 4837.5 4771.0 4772.6 4783.9 4787.1 4798.8 3569.5 3667.2 3669.7 3679.3 3688.8 3649.3 3690.6 3757.7 3785.5 3823.7 3688.8 3730.6 3731.9 3736.8 3752.3 3559.1 3735.6 3739.6 3752.7 3760.1 3133.9 3191.0 3307.9 3315.8 3325.6 4554.8 4584.3 4587.3 4590.0 4592.7 4231.6 4240.3 4242.4 4264.1 4291.4 4898.9 4962.5 4967.3 4976.4 5024.9 3545.9 3562.5 3563.5 3565.4 3577.2 5234.4 5244.2 5255.9 5270.4 5285.8 3605.5 3683.7 3701.1 3708.8 3721.8 3685.6 3692.9 3716.5 3723.4 3729.8 4211.4 4211.7 4214.2 4260.6 4266.4 4985.6 5024.7 5034.3 5036.5 5049.9 1206.9 1270.0 1270.5 1289.1 1315.1 780.2 784.3 788.0 797.5 805.0 3974.7 3979.7 4003.2 4022.3 4046.4 4606.9 4619.5 4623.6 4640.7 4662.2 3997.7 4002.3 4036.7 4044.5 4071.7 2373.2 2465.9 2469.4 2477.7 2669.4 4831.2 4949.8 4956.4 4956.8 4963.0 5310.2 5324.9 5343.5 5347.2 5367.2 4738.8 4931.9 4931.9 4933.8 4938.5 5095.4 5098.9 5164.9 5193.2 5204.4 5099.6 5143.5 5146.5 5164.8 5170.9 4749.9 4960.9 4976.3 4982.1 4982.2 3577.3 3580.4 3597.5 3604.2 3607.9 5188.8 5206.6 5234.8 5237.3 5249.7 4770.3 5053.6 5055.0 5063.7 5084.4 5050.7 5067.9 5070.7 5078.8 5101.2 4985.7 4999.7 5006.8 5009.0 5011.0 4876.5 5058.4 5082.5 5100.1 5118.4 4985.4 4996.6 5001.2 5009.2 5014.8 18:07:02 INFO - PROCESS | 20495 | 18:07:02 INFO - PROCESS | 20495 | [#5] yui.html Cycles:180 Average:12970.91 Median:45313.89 stddev:14976.82 (33.1%) stddev-sans-first:14990.20 18:07:02 INFO - PROCESS | 20495 | Values: 639.3 696.2 711.1 729.5 773.6 11716.7 12430.8 12432.9 12452.3 12461.2 11312.0 11732.5 11771.9 11782.6 11800.9 777.2 794.2 795.0 795.5 795.8 624.2 771.7 777.6 785.8 792.5 807.2 807.5 807.5 807.7 807.8 652.6 803.0 803.3 803.8 805.8 594.1 594.4 595.3 613.1 614.3 10698.0 10756.5 10779.5 10780.8 10793.1 11811.8 12285.7 12581.8 12655.6 12674.4 10480.9 10577.7 10582.6 10692.0 10898.7 3992.5 4002.5 4018.4 4040.0 4107.1 43801.6 44089.3 45512.0 45616.5 45716.8 9817.8 9828.7 9843.9 9858.1 10108.2 17321.3 17448.7 17480.6 17495.1 17550.7 6492.6 6550.8 6628.2 6671.3 6675.4 3528.9 3628.6 3645.9 3692.0 3743.9 2790.8 2885.8 2888.0 2890.5 2891.8 524.0 524.5 530.9 534.4 535.6 464.7 473.7 476.9 477.8 506.9 6814.5 6877.0 6931.9 7011.0 7032.4 502.6 506.8 515.0 526.6 530.5 3288.9 3414.1 3499.7 3507.8 3574.3 47220.3 48818.8 48952.0 54358.2 55767.0 48731.8 49600.8 49853.3 49865.5 50423.7 44580.3 45029.8 45115.8 46863.8 47408.3 8028.6 8328.2 8366.0 8371.9 8473.7 31472.4 31978.0 32085.8 32532.7 33964.8 39240.4 39624.6 40454.4 43559.6 45748.0 11082.7 11382.3 11516.7 11659.4 12060.8 11509.8 11692.7 11861.7 12008.9 12223.6 10461.1 10587.7 10720.5 10934.9 11050.4 10159.5 10425.7 10714.5 10777.6 11085.2 10194.7 10436.4 10548.5 10576.4 10791.0 9411.2 9516.8 9522.6 9944.8 10031.8 9197.0 9366.8 9479.1 9499.5 9557.5 18:07:02 INFO - PROCESS | 20495 | -------- Summary: end -------- 18:07:02 INFO - PROCESS | 20495 | 18:07:03 INFO - TEST-INFO | 20495: exit 0 18:07:03 INFO - TEST-OK | dromaeo_css | took 1117779ms 18:07:03 INFO - TEST-START | kraken 18:07:03 INFO - operating with platform_type : linux_ 18:07:03 INFO - Initialising browser for kraken test... 18:07:03 INFO - TEST-INFO | started process 20679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyTmhCl/profile http://localhost:41118/getInfo.html) 18:07:07 INFO - PROCESS | 20679 | __metrics Screen width/height:1600/1200 18:07:07 INFO - PROCESS | 20679 | colorDepth:24 18:07:07 INFO - PROCESS | 20679 | Browser inner width/height: 1024/697 18:07:07 INFO - PROCESS | 20679 | __metrics 18:07:08 ERROR - PROCESS | 20679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:07:09 INFO - TEST-INFO | 20679: exit 0 18:07:09 INFO - Browser initialized. 18:07:09 INFO - Running cycle 1/1 for kraken test... 18:07:09 INFO - TEST-INFO | started process 20762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyTmhCl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:07:21 INFO - PROCESS | 20762 | RSS: Main: 156958720 18:07:21 INFO - PROCESS | 20762 | 18:07:24 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41118/tests/kraken/driver.html?testName=audio-beat-detection) 18:07:25 INFO - PROCESS | 20762 | RSS: Main: 215359488 18:07:25 INFO - PROCESS | 20762 | 18:07:28 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41118/tests/kraken/driver.html?testName=audio-dft) 18:07:28 INFO - PROCESS | 20762 | RSS: Main: 203702272 18:07:28 INFO - PROCESS | 20762 | 18:07:31 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41118/tests/kraken/driver.html?testName=audio-fft) 18:07:32 INFO - PROCESS | 20762 | RSS: Main: 270290944 18:07:32 INFO - PROCESS | 20762 | 18:07:34 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41118/tests/kraken/driver.html?testName=audio-oscillator) 18:07:35 INFO - PROCESS | 20762 | RSS: Main: 180330496 18:07:35 INFO - PROCESS | 20762 | 18:07:37 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41118/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:07:38 INFO - PROCESS | 20762 | RSS: Main: 184307712 18:07:38 INFO - PROCESS | 20762 | 18:07:41 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41118/tests/kraken/driver.html?testName=imaging-darkroom) 18:07:41 INFO - PROCESS | 20762 | RSS: Main: 220135424 18:07:41 INFO - PROCESS | 20762 | 18:07:45 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41118/tests/kraken/driver.html?testName=imaging-desaturate) 18:07:46 INFO - PROCESS | 20762 | RSS: Main: 216211456 18:07:46 INFO - PROCESS | 20762 | 18:07:48 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41118/tests/kraken/driver.html?testName=json-parse-financial) 18:07:49 INFO - PROCESS | 20762 | RSS: Main: 219168768 18:07:49 INFO - PROCESS | 20762 | 18:07:51 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41118/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:07:51 INFO - PROCESS | 20762 | RSS: Main: 207073280 18:07:51 INFO - PROCESS | 20762 | 18:07:53 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:07:54 INFO - PROCESS | 20762 | RSS: Main: 209059840 18:07:54 INFO - PROCESS | 20762 | 18:07:56 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:07:57 INFO - PROCESS | 20762 | RSS: Main: 217571328 18:07:57 INFO - PROCESS | 20762 | 18:08:00 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:08:00 INFO - PROCESS | 20762 | RSS: Main: 222400512 18:08:00 INFO - PROCESS | 20762 | 18:08:03 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:08:04 INFO - PROCESS | 20762 | RSS: Main: 222687232 18:08:04 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | Cycle 1(1): loaded http://localhost:41118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41118/tests/kraken/driver.html?testName=ai-astar) 18:08:06 INFO - PROCESS | 20762 | RSS: Main: 265158656 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | __start_tp_report 18:08:06 INFO - PROCESS | 20762 | _x_x_mozilla_page_load 18:08:06 INFO - PROCESS | 20762 | _x_x_mozilla_page_load_details 18:08:06 INFO - PROCESS | 20762 | |i|pagename|runs| 18:08:06 INFO - PROCESS | 20762 | |0;ai-astar;112;110;113;114;114;114;113;114;112;112 18:08:06 INFO - PROCESS | 20762 | |1;audio-beat-detection;165;131;133;184;131;131;148;137;188;129 18:08:06 INFO - PROCESS | 20762 | |2;audio-dft;161;162;160;162;160;162;161;159;160;161 18:08:06 INFO - PROCESS | 20762 | |3;audio-fft;76;75;78;112;76;75;75;75;98;75 18:08:06 INFO - PROCESS | 20762 | |4;audio-oscillator;84;84;115;85;85;84;132;83;85;119 18:08:06 INFO - PROCESS | 20762 | |5;imaging-gaussian-blur;118;117;118;117;117;118;117;117;117;117 18:08:06 INFO - PROCESS | 20762 | |6;imaging-darkroom;146;146;146;146;145;146;146;145;146;146 18:08:06 INFO - PROCESS | 20762 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 18:08:06 INFO - PROCESS | 20762 | |8;json-parse-financial;63;63;62;64;64;63;63;64;62;64 18:08:06 INFO - PROCESS | 20762 | |9;json-stringify-tinderbox;65;65;65;65;64;65;66;65;65;66 18:08:06 INFO - PROCESS | 20762 | |10;stanford-crypto-aes;92;84;84;83;84;84;84;84;113;85 18:08:06 INFO - PROCESS | 20762 | |11;stanford-crypto-ccm;130;132;140;138;136;140;140;145;140;140 18:08:06 INFO - PROCESS | 20762 | |12;stanford-crypto-pbkdf2;170;178;171;173;181;176;174;171;169;169 18:08:06 INFO - PROCESS | 20762 | |13;stanford-crypto-sha256-iterative;66;68;66;66;67;66;66;67;71;69 18:08:06 INFO - PROCESS | 20762 | __end_tp_report 18:08:06 INFO - PROCESS | 20762 | __start_cc_report 18:08:06 INFO - PROCESS | 20762 | _x_x_mozilla_cycle_collect,1902 18:08:06 INFO - PROCESS | 20762 | __end_cc_report 18:08:06 INFO - PROCESS | 20762 | __startTimestamp1465348086308__endTimestamp 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | ------- Summary: start ------- 18:08:06 INFO - PROCESS | 20762 | Number of tests: 14 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.50 stddev:1.32 (1.2%) stddev-sans-first:1.36 18:08:06 INFO - PROCESS | 20762 | Values: 112.0 110.0 113.0 114.0 114.0 114.0 113.0 114.0 112.0 112.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:142.50 stddev:22.99 (16.1%) stddev-sans-first:23.52 18:08:06 INFO - PROCESS | 20762 | Values: 165.0 131.0 133.0 184.0 131.0 131.0 148.0 137.0 188.0 129.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 18:08:06 INFO - PROCESS | 20762 | Values: 161.0 162.0 160.0 162.0 160.0 162.0 161.0 159.0 160.0 161.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.50 stddev:12.85 (17.0%) stddev-sans-first:13.48 18:08:06 INFO - PROCESS | 20762 | Values: 76.0 75.0 78.0 112.0 76.0 75.0 75.0 75.0 98.0 75.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:18.70 (22.3%) stddev-sans-first:19.36 18:08:06 INFO - PROCESS | 20762 | Values: 84.0 84.0 115.0 85.0 85.0 84.0 132.0 83.0 85.0 119.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:08:06 INFO - PROCESS | 20762 | Values: 118.0 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 18:08:06 INFO - PROCESS | 20762 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:08:06 INFO - PROCESS | 20762 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.50 stddev:0.79 (1.2%) stddev-sans-first:0.83 18:08:06 INFO - PROCESS | 20762 | Values: 63.0 63.0 62.0 64.0 64.0 63.0 63.0 64.0 62.0 64.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:08:06 INFO - PROCESS | 20762 | Values: 65.0 65.0 65.0 65.0 64.0 65.0 66.0 65.0 65.0 66.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:84.00 stddev:9.25 (11.0%) stddev-sans-first:9.68 18:08:06 INFO - PROCESS | 20762 | Values: 92.0 84.0 84.0 83.0 84.0 84.0 84.0 84.0 113.0 85.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:140.00 stddev:4.38 (3.1%) stddev-sans-first:3.54 18:08:06 INFO - PROCESS | 20762 | Values: 130.0 132.0 140.0 138.0 136.0 140.0 140.0 145.0 140.0 140.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:173.50 stddev:4.05 (2.3%) stddev-sans-first:4.13 18:08:06 INFO - PROCESS | 20762 | Values: 170.0 178.0 171.0 173.0 181.0 176.0 174.0 171.0 169.0 169.0 18:08:06 INFO - PROCESS | 20762 | 18:08:06 INFO - PROCESS | 20762 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.69 (2.5%) stddev-sans-first:1.73 18:08:06 INFO - PROCESS | 20762 | Values: 66.0 68.0 66.0 66.0 67.0 66.0 66.0 67.0 71.0 69.0 18:08:06 INFO - PROCESS | 20762 | -------- Summary: end -------- 18:08:06 INFO - PROCESS | 20762 | 18:08:07 INFO - TEST-INFO | 20762: exit 0 18:08:07 INFO - TEST-OK | kraken | took 63781ms 18:08:07 INFO - SUITE-END | took 1181s 18:08:07 INFO - Completed test suite (00:19:41) 18:08:07 INFO - javascript benchmark 18:08:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.7223041580633, 2663.06792622582, 2687.8252913560623, 2698.2579790557593, 2701.959470607941, 4635.188841952653, 4644.280136578827, 4647.465541462733, 4718.197906355919, 4753.47711751707, 4644.489106198315, 4687.624990000801, 4722.3860898083285, 4742.05158968206, 4769.833055843046, 4296.63478604319, 4310.03024319528, 4312.676549258803, 4337.522872484024, 4342.761148136851, 113.08894630297635, 4384.660672767001, 4428.7121337112985, 4432.246518091933, 4457.955420445786, 4289.785510724467, 4362.062156636307, 4401.603855652975, 4407.2948328267485, 4410.316400957825, 4355.0, 4364.803583838735, 4365.890852728676, 4407.867763967054, 4411.955880441186, 4248.4052232687445, 4327.091310824699, 4334.219840428722, 4342.978285108586, 4389.473263208435, 4574.0, 4614.215583350839, 4615.030843522864, 4617.861464156081, 4625.884352891171, 4955.430125535538, 4958.727270000149, 4992.176290911995, 5064.645474816764, 5086.491350864921, 5015.247712843103, 5060.645754797166, 5070.112730272188, 5070.518300761421, 5073.315102461157, 4600.60894823937, 4695.483496815347, 4710.387649605598, 4713.6936099153445, 4721.150192965265, 5141.177411614199, 5189.55888749453, 5194.0, 5260.13207820713, 5260.605454590921, 4873.293372460973, 4919.385076865392, 4921.212605983025, 4928.950710492849, 4968.677035992649, 4967.726775027336, 5020.0, 5075.086484432837, 5089.05852417304, 5137.563307118937, 4985.9750701246985, 4994.825181118644, 5014.52362025607, 5021.899562008739, 5053.1157047516535, 4994.825181118644, 5005.674631148963, 5012.223105418593, 5014.724190169502, 5049.318342023853, 4128.855490057834, 4166.416701661769, 4168.874933751992, 4174.91650167001, 4220.2192594370135, 3666.046827824785, 3682.024263570156, 3693.796841173761, 3710.573284072312, 3772.43413487979, 4372.3878656988045, 4374.934375984363, 4383.561643835591, 4410.911781764346, 4423.778811059498, 4914.164592019365, 5093.439721630618, 5102.979404119191, 5110.131277682803, 5110.463401342806, 4324.589164029412, 4324.935125973113, 4348.043430445297, 4352.9988102735915, 4357.085012147421, 4477.7984990675495, 4487.282034874404, 4508.842190523316, 4525.0497395546645, 4550.044490656999, 4722.52774722525, 4788.473267940454, 4795.352627395327, 4801.4958429364415, 4803.087413391375, 5086.949130508648, 5095.872603184802, 5161.483851614808, 5167.638265321391, 5173.870653233699, 4828.275758636092, 4828.855134345975, 4830.541098595628, 4832.758362081952, 4875.975620121877, 4857.902841943071, 5029.321041659329, 5029.59763218949, 5033.823816166417, 5082.796688132434, 5018.548330650259, 5046.9747651261505, 5067.645264831426, 5076.923846142385, 5080.034793389244, 4927.408710954708, 4929.088118698052, 4934.925976110433, 4938.629602779878, 4984.352034235526, 4773.928391074067, 4857.6599638025, 4919.532644398776, 4937.5062493751775, 4994.32576602154, 4800.3279540865005, 4822.807087716452, 4841.370621819141, 4869.0, 4888.7311197884455, 4912.582430493448, 4934.7285899275885, 4941.382327209099, 4943.431505376784, 4946.258061290835, 4763.30932014849, 4811.422629284508, 4824.638152138534, 4878.243872199815, 4923.950760492349], "value": 4604.198650758798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1232995701812, 596.6390333848025, 602.5089552015107, 602.6896148483529, 629.1003864473804, 27518.312042198955, 39535.80232098839, 40201.39697904526, 40279.59720402795, 40306.798466007705, 42485.0, 43090.7845460773, 43108.56891431085, 43192.352114718306, 43237.13525729484, 7500.549967001997, 7734.651940662684, 7912.129665736765, 7917.287444130027, 10042.096211340979, 9672.22622190223, 10064.094231519162, 10073.294869359148, 10086.445245511493, 10086.798264034715, 10080.798384032352, 10154.89845101547, 10155.949220253888, 10159.644412445567, 10160.33957792741, 7574.734884279023, 7810.687572497093, 7811.062672479282, 10023.0, 10031.448270345129, 9780.021997800162, 10173.186145108373, 10180.949095254551, 10181.592736290539, 10186.847197292047, 38346.42480362769, 38706.41940370897, 38708.61291387107, 38779.22441551153, 38806.80596597027, 2947.3957838643028, 2975.880964761407, 2989.0733872499595, 3100.4419204543174, 3434.9180008297308, 28782.136535903956, 29429.411411771645, 29449.263768405748, 30488.54267185994, 31868.0, 3327.085051610826, 3344.8160351180677, 3398.2693720850134, 3413.1808365991983, 3452.378571857065, 254355.0, 255938.0, 256550.71724641445, 257015.0, 258043.0, 31974.0, 56785.0, 57242.7137864308, 57324.71337643368, 57333.71333143308, 32712.836435818146, 34289.0, 34334.82832585821, 34362.31275374479, 34382.140446488535, 13286.66783330405, 13467.528636497871, 13482.595522134352, 13483.595492135251, 13493.865061349261, 9936.0, 11369.090472762324, 11512.194146409838, 11631.53473861036, 11833.822492662617, 7952.648049831542, 7958.0, 7985.480943738638, 8023.277904988579, 8035.9508325376655, 3101.4727496325677, 3121.9219519512194, 3122.937541249162, 3169.5245713142754, 3200.6639302873327, 818.1940788323496, 847.2713466418875, 933.8793447862593, 959.5058544849409, 1053.2837670384213, 26798.330041748723, 28519.32895696567, 28729.56905646417, 28738.13785586436, 28990.27524311909, 879.7404765594165, 892.2817132208635, 896.7399454158351, 936.4755736787421, 1037.8131936251548, 14907.925460372846, 14908.925455372872, 15167.620809479631, 15410.845891541165, 15486.22568871552, 394824.0, 398190.0, 398516.0, 398916.0, 399321.0, 50503.74748126309, 51109.23336149961, 51158.23262651063, 51184.23223651648, 51272.0, 1068.6046162919613, 1141.49774099396, 1167.8073117935614, 1172.4841069929205, 1192.5170306026073, 28329.71670283271, 28915.7108428913, 29005.38413129699, 29336.413271734873, 29520.852395737882, 43154.136917262105, 43338.349924750524, 43687.78156109326, 43716.56283437125, 43762.56237437585, 306032.0, 307430.0, 307947.0, 308304.0, 308857.0, 21300.147994080682, 24300.392490187885, 25343.0, 25394.619080714172, 25468.617970730826, 41641.334346625794, 41919.371209432495, 42060.57939420567, 42540.57459425366, 42781.0, 36592.81703591465, 36676.63323366839, 36793.448098278015, 37066.0, 37194.442083369315, 35550.46674299836, 36384.45423318599, 36847.0, 36880.26239475249, 37171.256574868894, 31868.0, 33294.0, 36326.0, 36506.452403214505, 36638.8168059158, 31947.36105277928, 32696.50955235721, 32882.75227706728, 33081.50377744384, 33544.83227583944, 24989.500209995334, 25128.24615261472, 25222.117225897007, 25596.744032559436, 25662.743372566034], "value": 17003.416522593092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.5783943588822, 354.7445838995922, 356.23942881947045, 366.0756589611233, 372.7688832923588, 15836.524904252876, 16880.155992200373, 17016.829831701678, 17340.653186936255, 17631.0, 16257.0, 16415.425460108898, 16623.251953662086, 16802.579935501617, 16855.578610534743, 4935.728534930578, 5141.845744627667, 5181.248718935752, 5186.766595503212, 5191.584673226152, 3701.130234394915, 3864.0339915021245, 4976.0794253063295, 5692.487676109149, 6024.2168518092585, 6034.728437220314, 6053.848653783647, 6095.0, 6111.511079113682, 6111.511079113682, 5891.23413956183, 5959.553033522481, 6050.092486127072, 6054.485368743662, 6082.665453400084, 3358.177246574605, 4852.757362131914, 5122.410922743895, 5127.461616530248, 5228.5817134629515, 20038.398848034583, 20745.377638670863, 20773.792262077488, 20829.479263018547, 20880.164793408247, 1920.3374835681668, 2159.8164156046755, 2241.1371621925514, 2383.3088404362716, 2464.827462077655, 12651.810222846665, 13156.473741050346, 13228.338583070905, 13646.385912633956, 13716.794248086288, 1694.1952572528073, 1755.4909076367837, 1766.3023105873237, 1775.520609435458, 1804.1790985101748, 61256.387436125515, 61897.690511547604, 62025.27848329115, 62522.68738656323, 63348.46606135749, 8494.660213591387, 8813.735587932373, 8912.8663070054, 8940.418872773249, 9035.186833185046, 12817.1668841525, 12945.611631650878, 12988.545400910923, 13018.934905325603, 13026.088173827931, 7060.3286291128325, 7194.38847697941, 7210.819729506804, 7247.166575843846, 7291.4531410144455, 7226.6386680665755, 7245.239249878687, 7401.148867880155, 7414.406847452191, 7465.70137194517, 5784.913226301609, 5854.882902341929, 5920.644761314335, 5950.9107363389585, 6008.66952317618, 1501.8122734658168, 1552.5109590478962, 1554.6735185611149, 1670.9081000545086, 1702.1234064456814, 822.880682301063, 860.0066922704286, 946.990530094704, 948.1892981500717, 977.1694060049043, 16417.83582164163, 16931.830681693027, 17441.12794360277, 17615.383461578782, 17818.821811781716, 830.4975489828687, 834.0366876257932, 844.3287586368851, 864.1401805203863, 895.5746020640144, 7987.281144696889, 9585.185259252901, 9617.71146865581, 9924.610554522373, 10135.797284054424, 362586.0, 364788.0, 376914.0, 377901.0, 383341.0, 51024.23463648048, 56873.0, 58400.415995839496, 62675.37324626695, 63270.0, 929.3819609959437, 945.6737425588161, 954.8663187153666, 957.1577012229194, 961.2214106573558, 15655.765163522328, 16089.356425743308, 16207.75688364699, 16274.16415782, 16317.510474685778, 32295.838520807247, 32448.513272301407, 33154.834225828716, 33830.15424614404, 33907.83046084754, 40725.0, 43188.784056079516, 43650.34524482072, 43717.78141109274, 43810.34284485799, 6990.510664253454, 7078.362947334765, 7217.925837794922, 7327.194008659149, 7358.080239970004, 10923.836142458029, 11246.100311975186, 11300.078986572467, 11358.829617555908, 11526.59656912004, 9064.954675226581, 9479.952600237231, 9657.372270802376, 9774.902250977684, 9896.653617123366, 9588.11479485297, 9821.36161149523, 9901.405915645084, 9947.452890091114, 10008.0, 9071.0, 9832.115109640166, 9847.409155450696, 10085.899141008496, 10116.19070474375, 7016.684249208696, 10383.94808025955, 10430.165586753194, 10624.468776560987, 10748.570057197938, 9310.72067837966, 9811.0, 10169.692771978489, 10209.285350025428, 10283.794324113325], "value": 8185.613241796295, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.52008976544, 1410.464023671005, 1435.146088077594, 1537.2390666620017, 1714.1343621471156, 6746.460283177346, 7544.4718869679145, 7724.1889601591865, 7753.806154846138, 7935.52386856789, 7656.0, 7829.843403131934, 7830.765077047698, 7838.64726087326, 7844.058712954439, 1883.1525813384033, 1930.054273406028, 1931.5943651833131, 2157.751858536265, 2200.119952019197, 1984.5534754680154, 2163.902624381899, 2199.9560008799895, 2211.137656314039, 2226.8107210887015, 2172.0768673313855, 2180.858244214121, 2193.5722534105857, 2195.121951219517, 2197.2419515267206, 1835.3392778599693, 2127.2554605887894, 2134.103676455892, 2135.2099723102397, 2136.9893150534303, 2162.048698572639, 2213.0594497338566, 2213.4798322394226, 2233.653783663535, 2236.1726161320253, 7711.26742959418, 7739.071311442663, 7745.419093567949, 7761.805954851117, 7767.223277672244, 4542.500324964251, 4640.280756482751, 4642.9535704643195, 4644.698094623873, 4651.232546629802, 2810.409813939055, 2866.7993240473174, 2874.4107457971277, 2881.919280269899, 2883.2935930696904, 843.2579330189456, 873.9825203495894, 878.2578720980761, 880.5377176982136, 882.0914458108159, 10250.128739057189, 10279.79440411195, 10288.0, 10292.279540432173, 10324.483775811255, 4063.674906007512, 4150.730202536825, 4241.97879010603, 4242.893927651833, 4275.358696195595, 8677.956610217036, 8758.605862736193, 8761.34289928258, 8763.211310982042, 8766.517841518651, 4629.259318509023, 4672.182368085571, 4674.462436819741, 4680.485146633868, 4684.672072954861, 3672.8530858765885, 3773.132179598708, 4217.8523751668545, 4272.060146767706, 4273.55127711592, 3650.5071815305123, 3749.775013499199, 3762.3415902217002, 3793.3740932745786, 3796.468494410785, 1174.3130268792852, 1174.94712737927, 1187.4062968515743, 1192.0582739635763, 1199.3343694249775, 646.8706258748269, 692.4149094015548, 798.1499702816482, 804.7545498622976, 827.9710210142616, 5587.3015873015875, 5597.020521408737, 5600.299962504687, 5619.719014049363, 5697.344805347355, 874.2525141004477, 927.4852456886492, 941.3128416256171, 969.7284760267137, 985.8274801909637, 3379.1552111972005, 3394.5077963695126, 3409.7783644063056, 3496.4580490024086, 3584.9103772405897, 9367.0, 9412.0, 9649.565769540388, 9657.661981830603, 9660.37207581505, 9452.054794520493, 9512.667056652985, 9515.666951656658, 9531.094546018117, 9534.332596718163, 7281.854362912712, 7376.7049318026675, 7407.481476296715, 7420.072490938633, 7421.257874212643, 4948.257761335828, 4991.9500804991485, 5105.387353517602, 5123.5644970177955, 5186.040582492251, 8663.783405414915, 8738.169873861973, 8796.252318552739, 8799.648014079365, 8933.731988040368, 8666.566671666265, 8886.333524985523, 9187.310951678517, 9190.770230744285, 9196.954015229881, 6287.779927702509, 6299.212598425197, 6414.294427613052, 6439.162908822011, 6439.935600643934, 7355.411567074514, 7385.150707668687, 7424.6287685614425, 7434.73978410753, 7435.256474352522, 6673.8998915015345, 6833.2483426824, 6839.692213850488, 6933.5493192942295, 6958.165020197609, 6572.868542629216, 6625.271220165874, 6695.330466953265, 6745.190577130579, 6848.623325716934, 6074.149619053248, 6218.81343559694, 6508.511861610493, 6519.478441724746, 6534.150560427114, 4584.88537786558, 4595.494495605414, 4642.16441040616, 4674.953250467452, 4752.42970843501, 4625.930611040904, 4683.648726345469, 4722.85831425044, 4753.786079626356, 4782.856514304576], "value": 4105.239907156148, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.535811984585, 292.6809777342696, 295.426871868575, 298.40914988323135, 298.70875896001417, 4756.524347565246, 4790.904181916364, 4814.205656066753, 4824.204006338954, 4837.540433658805, 4770.976145119278, 4772.570468657829, 4783.856484305467, 4787.085165933629, 4798.760061996895, 3569.4824250483666, 3667.193217492161, 3669.6880765134997, 3679.319325924699, 3688.8155592220414, 3649.3431182387158, 3690.5940346561856, 3757.718171137162, 3785.4889589905424, 3823.655870971612, 3688.79711615861, 3730.608286129958, 3731.891481313063, 3736.775793452388, 3752.3433399155037, 3559.148456168665, 3735.62643735627, 3739.551253849542, 3752.737308388414, 3760.078780698719, 3133.905982820519, 3190.978886756239, 3307.9007629771145, 3315.784474009182, 3325.551050608161, 4554.772261386917, 4584.33527138563, 4587.288970209623, 4589.977050114761, 4592.724436533818, 4231.555686652888, 4240.342746874239, 4242.4272723182285, 4264.125854199881, 4291.420658211133, 4898.853034408973, 4962.528559786814, 4967.304577359173, 4976.35307410038, 5024.899502009939, 3545.911352216215, 3562.5240457881782, 3563.465480177968, 3565.3760591896316, 3577.159367548624, 5234.42421333653, 5244.187150991603, 5255.894882102375, 5270.446603106657, 5285.7885684572575, 3605.4772058051435, 3683.6684698377276, 3701.0747313171705, 3708.8145592720257, 3721.832517536717, 3685.576158741779, 3692.9446058309145, 3716.5168528091176, 3723.385641369198, 3729.8180564370145, 4211.389348544505, 4211.663066954698, 4214.178235244098, 4260.616544511009, 4266.4240327556, 4985.626078044161, 5024.748762561857, 5034.320366750515, 5036.546710796046, 5049.924251136236, 1206.8551773787026, 1270.0347735720918, 1270.4854533913779, 1289.071868254855, 1315.0860152194134, 780.2314720000908, 784.3489903380181, 787.9842403151905, 797.4776521378507, 804.9758507244792, 3974.682025437958, 3979.6816254699556, 4003.179348233605, 4022.2960981828646, 4046.3728122141115, 4606.917374417009, 4619.491855895849, 4623.56076172763, 4640.69835460694, 4662.181749117847, 3997.700172487075, 4002.3196056670436, 4036.6972477064337, 4044.5348784889525, 4071.6742660587083, 2373.1693907132712, 2465.9136930207355, 2469.4196863737006, 2477.715062767769, 2669.4394177223003, 4831.227003679394, 4949.7772600233075, 4956.430010548761, 4956.801727930914, 4962.950370496321, 5310.203469479532, 5324.946750532523, 5343.492368225013, 5347.1979203119845, 5367.228814491208, 4738.763061846894, 4931.876703082452, 4931.914978704679, 4933.827316043921, 4938.481459446706, 5095.388553373471, 5098.898022039612, 5164.922526162036, 5193.195054766517, 5204.427512973645, 5099.643024988215, 5143.5113664201845, 5146.485351464824, 5164.767585458589, 5170.870728231674, 4749.928751068667, 4960.900781984412, 4976.328195693535, 4982.131700612271, 4982.177940639754, 3577.3203091412547, 3580.373434648874, 3597.496350510878, 3604.2250916052126, 3607.8917632471066, 5188.766505507186, 5206.6095042871175, 5234.842954711365, 5237.2667826505085, 5249.658772179796, 4770.260609605517, 5053.59571234293, 5054.974725126351, 5063.7468126592785, 5084.440711521655, 5050.722210278323, 5067.949320506747, 5070.721110338966, 5078.806480477011, 5101.183810590288, 4985.675931064469, 4999.675021123615, 5006.799728010838, 5008.974955125201, 5010.974945125251, 4876.472635084726, 5058.3929928408825, 5082.466341034138, 5100.107481190851, 5118.43697193301, 4985.401751789664, 4996.625253105959, 5001.24981252815, 5009.198528235611, 5014.824481143142], "value": 3739.4281039358743, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.3446717114956, 696.2237105627221, 711.0898050495367, 729.4747781597251, 773.6286582440426, 11716.707082322946, 12430.751384972295, 12432.875671243284, 12452.31512266825, 12461.190022648521, 11312.0, 11732.472038758255, 11771.941140294286, 11782.58760943365, 11800.940995295034, 777.1878387085509, 794.1661255681511, 794.9880751788728, 795.5028107432854, 795.8090058386003, 624.1636207481973, 771.6797529025448, 777.5917643237317, 785.8428314337127, 792.4611264340247, 807.2331285278979, 807.4630370803409, 807.4953154278576, 807.6809660184224, 807.8424707182102, 652.5888690125191, 802.9598520074002, 803.317180396664, 803.8151225218203, 805.8066064144592, 594.0524862843757, 594.378874076591, 595.3391735173954, 613.1171113596452, 614.2966303582418, 10698.0, 10756.515956781963, 10779.461026948622, 10780.838287425695, 10793.136549076055, 11811.763764724743, 12285.69285767854, 12581.811272830915, 12655.557055503014, 12674.36628168588, 10480.94759526205, 10577.682669519927, 10582.629607963761, 10692.0, 10898.673039808738, 3992.480977472939, 4002.5397079335667, 4018.417329487237, 4039.9596004039877, 4107.116969851496, 43801.56198438007, 44089.338659920126, 45512.0, 45616.54383456156, 45716.77141614271, 9817.754556136011, 9828.705138845846, 9843.901560984299, 9858.063483969012, 10108.191344692406, 17321.30714771395, 17448.65102697964, 17480.562985925455, 17495.125243737763, 17550.736738948926, 6492.577982431127, 6550.770723024767, 6628.23775265841, 6671.299513551105, 6675.432588229958, 3528.894133176009, 3628.63713628635, 3645.927081458356, 3691.9815400922826, 3743.8502459901842, 2790.7627851632838, 2885.7691384689174, 2887.956680649792, 2890.5375139977505, 2891.79757416983, 523.9869003274872, 524.4912434938104, 530.8938212357467, 534.3934634190144, 535.61703382082, 464.7095565271705, 473.7228721198069, 476.94992025836785, 477.7873846138435, 506.8656805946427, 6814.454843612499, 6877.0, 6931.861362772716, 7011.0, 7032.437405007587, 502.5928997512027, 506.83527853447777, 514.9948500515022, 526.5734754848509, 530.541081964106, 3288.852001659931, 3414.1073303770986, 3499.720022398202, 3507.8421471033867, 3574.303010912847, 47220.29169562459, 48818.75590622024, 48952.0, 54358.18462723062, 55767.0, 48731.756341218774, 49600.75199624051, 49853.25220121701, 49865.50134498681, 50423.74788126036, 44580.3312950306, 45029.77485112619, 45115.77442112769, 46863.765681171375, 47408.28887566689, 8028.6387112578905, 8328.167183281623, 8366.0, 8371.916280837113, 8473.745787626382, 31472.370552588363, 31978.0, 32085.75399721997, 32532.674673252965, 33964.83017584896, 39240.411393828545, 39624.603753963245, 40454.393184101675, 43559.56440435555, 45748.0, 11082.722931926766, 11382.260153090023, 11516.654500365003, 11659.417029148339, 12060.75878482443, 11509.769804604028, 11692.70768230801, 11861.703457413634, 12008.939955300168, 12223.633291001284, 10461.110805581611, 10587.682369528926, 10720.463976801284, 10934.890651093387, 11050.392228427514, 10159.492025398848, 10425.687229383131, 10714.51784669676, 10777.568897244022, 11085.224034317844, 10194.694159175237, 10436.37381757097, 10548.472576370998, 10576.36541807494, 10791.0, 9411.200047995997, 9516.762080948032, 9522.571484283362, 9944.801103978025, 10031.799364012824, 9197.0, 9366.81266374655, 9479.146876781122, 9499.47752873571, 9557.52212389364], "value": 5858.400393745468, "unit": "score"}], "name": "dromaeo_css", "value": 6215.160184153539, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 131.0, 133.0, 184.0, 131.0, 131.0, 148.0, 137.0, 188.0, 129.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 162.0, 160.0, 162.0, 161.0, 159.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 78.0, 112.0, 76.0, 75.0, 75.0, 75.0, 98.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 115.0, 85.0, 85.0, 84.0, 132.0, 83.0, 85.0, 119.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 64.0, 64.0, 63.0, 63.0, 64.0, 62.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 84.0, 83.0, 84.0, 84.0, 84.0, 84.0, 113.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 140.0, 138.0, 136.0, 140.0, 140.0, 145.0, 140.0, 140.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 178.0, 171.0, 173.0, 181.0, 176.0, 174.0, 171.0, 169.0, 169.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0, 71.0, 69.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1550.9, "alertThreshold": 2.0}]} 18:08:07 INFO - Return code: 0 18:08:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:08:07 ERROR - # TBPL SUCCESS # 18:08:07 INFO - Running post-action listener: _resource_record_post_action 18:08:07 INFO - ##### 18:08:07 INFO - ##### Finished run-tests step (success) 18:08:07 INFO - ##### 18:08:07 INFO - Running post-run listener: _resource_record_post_run 18:08:07 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 441270272; Read time: 20; Write time: 473316 18:08:07 INFO - TinderboxPrint: CPU usage
13.1% 18:08:07 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 18:08:07 INFO - TinderboxPrint: I/O write bytes / time
441,270,272 / 473,316 18:08:07 INFO - TinderboxPrint: CPU idle
8,313.0 (86.9%) 18:08:07 INFO - TinderboxPrint: CPU user
1,213.9 (12.7%) 18:08:07 INFO - TinderboxPrint: Swap in / out
0 / 0 18:08:07 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 119701504; Read time: 0; Write time: 163764 18:08:07 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 8192; Write bytes: 279887872; Read time: 20; Write time: 253576 18:08:08 INFO - Running post-run listener: _upload_blobber_files 18:08:08 INFO - Blob upload gear active. 18:08:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:08:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:08:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:08:08 INFO - (blobuploader) - INFO - Open directory for files ... 18:08:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:08:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:08:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:11 INFO - (blobuploader) - INFO - Done attempting. 18:08:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:08:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:13 INFO - (blobuploader) - INFO - Done attempting. 18:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:08:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:47 INFO - (blobuploader) - INFO - Done attempting. 18:08:47 INFO - (blobuploader) - INFO - Iteration through files over. 18:08:47 INFO - Return code: 0 18:08:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:08:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:08:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bcf0f598784d8b4ef2378640b3a2eac1ca2bde540221c54e3a50cb710347b438db386ce4bb1309f89ea267adc1146eb8c2b3ced89e5c30b75ae8717ba9d16267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49bc7e8a75479f12b8b526fa35c3b1408be8b2e87f46f17cc5a0a4ae6d9e6316fae007cc46dc98a9e0a855ba552a2d2a8c326f28a445fdf746397d5ff7bf0227", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f2a1d3c340c01fb4d415adc01b8a91b64d17300df363aa2319e3d71500fb460e2e83a86400505638967c604e8b1dc0f39f2af1b27aff1c1394b9e8067d2b863"} 18:08:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:08:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:08:47 INFO - Contents: 18:08:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bcf0f598784d8b4ef2378640b3a2eac1ca2bde540221c54e3a50cb710347b438db386ce4bb1309f89ea267adc1146eb8c2b3ced89e5c30b75ae8717ba9d16267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49bc7e8a75479f12b8b526fa35c3b1408be8b2e87f46f17cc5a0a4ae6d9e6316fae007cc46dc98a9e0a855ba552a2d2a8c326f28a445fdf746397d5ff7bf0227", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f2a1d3c340c01fb4d415adc01b8a91b64d17300df363aa2319e3d71500fb460e2e83a86400505638967c604e8b1dc0f39f2af1b27aff1c1394b9e8067d2b863"} 18:08:47 INFO - Running post-run listener: copy_logs_to_upload_dir 18:08:47 INFO - Copying logs to upload dir... 18:08:47 INFO - mkdir: /builds/slave/test/build/upload/logs 18:08:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.501372 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-07 18:08:47.224271) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 18:08:47.227869) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bcf0f598784d8b4ef2378640b3a2eac1ca2bde540221c54e3a50cb710347b438db386ce4bb1309f89ea267adc1146eb8c2b3ced89e5c30b75ae8717ba9d16267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49bc7e8a75479f12b8b526fa35c3b1408be8b2e87f46f17cc5a0a4ae6d9e6316fae007cc46dc98a9e0a855ba552a2d2a8c326f28a445fdf746397d5ff7bf0227", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f2a1d3c340c01fb4d415adc01b8a91b64d17300df363aa2319e3d71500fb460e2e83a86400505638967c604e8b1dc0f39f2af1b27aff1c1394b9e8067d2b863"} build_url:https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/V4NBWz6AQJ-hPFUL-yyOww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bcf0f598784d8b4ef2378640b3a2eac1ca2bde540221c54e3a50cb710347b438db386ce4bb1309f89ea267adc1146eb8c2b3ced89e5c30b75ae8717ba9d16267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49bc7e8a75479f12b8b526fa35c3b1408be8b2e87f46f17cc5a0a4ae6d9e6316fae007cc46dc98a9e0a855ba552a2d2a8c326f28a445fdf746397d5ff7bf0227", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f2a1d3c340c01fb4d415adc01b8a91b64d17300df363aa2319e3d71500fb460e2e83a86400505638967c604e8b1dc0f39f2af1b27aff1c1394b9e8067d2b863"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 18:08:47.252708) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 18:08:47.253017) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1465346850.74156-1762033990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004707 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 18:08:47.339407) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 18:08:47.339741) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 18:08:47.340043) ========= ========= Total master_lag: 0.17 =========