builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-058 starttime: 1464373566.67 results: success (0) buildid: 20160527094749 builduid: b3bb58a737f04789a72cda3f35b6d24e revision: 545da344ffbd4cb48d171295b9084d6f6247e786 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.666419) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.666869) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.667143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.681782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.682042) ========= 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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 11:26:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.9M=0s 2016-05-27 11:26:06 (50.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.791295) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.791605) ========= 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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018542 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.879423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:06.879801) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 545da344ffbd4cb48d171295b9084d6f6247e786 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 545da344ffbd4cb48d171295b9084d6f6247e786 --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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 11:26:07,148 truncating revision to first 12 chars 2016-05-27 11:26:07,148 Setting DEBUG logging. 2016-05-27 11:26:07,148 attempt 1/10 2016-05-27 11:26:07,148 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/545da344ffbd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 11:26:07,461 unpacking tar archive at: larch-545da344ffbd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642218 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:07.536663) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:07.536993) ========= script_repo_revision: 545da344ffbd4cb48d171295b9084d6f6247e786 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:07.537368) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:07.537628) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:26:07.546876) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-27 11:26:07.547137) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:07 INFO - MultiFileLogger online at 20160527 11:26:07 in /builds/slave/test 11:26:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 11:26:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:07 INFO - {'append_to_log': False, 11:26:07 INFO - 'base_work_dir': '/builds/slave/test', 11:26:07 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 11:26:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:07 INFO - 'branch': 'Larch-Non-PGO', 11:26:07 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:07 INFO - 'config_files': ('talos/linux_config.py',), 11:26:07 INFO - 'default_actions': ('clobber', 11:26:07 INFO - 'read-buildbot-config', 11:26:07 INFO - 'download-and-extract', 11:26:07 INFO - 'populate-webroot', 11:26:07 INFO - 'create-virtualenv', 11:26:07 INFO - 'install', 11:26:07 INFO - 'run-tests'), 11:26:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:07 INFO - 'download_minidump_stackwalk': True, 11:26:07 INFO - 'download_symbols': 'ondemand', 11:26:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:26:07 INFO - 'tooltool.py': '/tools/tooltool.py', 11:26:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:26:07 INFO - '/tools/misc-python/virtualenv.py')}, 11:26:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:07 INFO - 'installer_path': 'installer.exe', 11:26:07 INFO - 'log_level': 'info', 11:26:07 INFO - 'log_name': 'talos', 11:26:07 INFO - 'log_to_console': True, 11:26:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:07 INFO - 'opt_config_files': (), 11:26:07 INFO - 'pip_index': False, 11:26:07 INFO - 'sps_profile': False, 11:26:07 INFO - 'sps_profile_interval': 0, 11:26:07 INFO - 'suite': 'dromaeojs', 11:26:07 INFO - 'system_bits': '32', 11:26:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:07 INFO - 'title': 'talos-linux64-ix-058', 11:26:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:07 INFO - 'use_talos_json': True, 11:26:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:07 INFO - 'work_dir': 'build'} 11:26:07 INFO - ##### 11:26:07 INFO - ##### Running clobber step. 11:26:07 INFO - ##### 11:26:07 INFO - Running pre-action listener: _resource_record_pre_action 11:26:07 INFO - Running main action method: clobber 11:26:07 INFO - rmtree: /builds/slave/test/build 11:26:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:08 INFO - Running post-action listener: _resource_record_post_action 11:26:08 INFO - ##### 11:26:08 INFO - ##### Finished clobber step (success) 11:26:08 INFO - ##### 11:26:08 INFO - ##### 11:26:08 INFO - ##### Running read-buildbot-config step. 11:26:08 INFO - ##### 11:26:08 INFO - Running pre-action listener: _resource_record_pre_action 11:26:08 INFO - Running main action method: read_buildbot_config 11:26:08 INFO - Using buildbot properties: 11:26:08 INFO - { 11:26:08 INFO - "project": "", 11:26:08 INFO - "product": "firefox", 11:26:08 INFO - "script_repo_revision": "production", 11:26:08 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 11:26:08 INFO - "repository": "", 11:26:08 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 11:26:08 INFO - "buildid": "20160527094749", 11:26:08 INFO - "pgo_build": "False", 11:26:08 INFO - "basedir": "/builds/slave/test", 11:26:08 INFO - "buildnumber": 2, 11:26:08 INFO - "slavename": "talos-linux64-ix-058", 11:26:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:26:08 INFO - "platform": "ubuntu64_hw", 11:26:08 INFO - "scheduler": "tests-larch-linux64-talos", 11:26:08 INFO - "branch": "larch", 11:26:08 INFO - "repo_path": "projects/larch", 11:26:08 INFO - "revision": "545da344ffbd4cb48d171295b9084d6f6247e786", 11:26:08 INFO - "stage_platform": "linux64", 11:26:08 INFO - "builduid": "b3bb58a737f04789a72cda3f35b6d24e", 11:26:08 INFO - "slavebuilddir": "test" 11:26:08 INFO - } 11:26:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 11:26:08 INFO - Running post-action listener: _resource_record_post_action 11:26:08 INFO - ##### 11:26:08 INFO - ##### Finished read-buildbot-config step (success) 11:26:08 INFO - ##### 11:26:08 INFO - ##### 11:26:08 INFO - ##### Running download-and-extract step. 11:26:08 INFO - ##### 11:26:08 INFO - Running pre-action listener: _resource_record_pre_action 11:26:08 INFO - Running main action method: download_and_extract 11:26:08 INFO - mkdir: /builds/slave/test/build/tests 11:26:08 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')]} 11:26:08 INFO - https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:26:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:26:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:26:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:09 INFO - Downloaded 1361 bytes. 11:26:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:26:09 INFO - Using the following test package requirements: 11:26:09 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:26:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:26:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'jsshell-linux-x86_64.zip'], 11:26:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:26:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:26:09 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:26:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:26:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:26:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:26:09 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')]} 11:26:09 INFO - https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:26:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:26:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:26:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:10 INFO - Downloaded 22848162 bytes. 11:26:10 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'] 11:26:10 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 11:26:11 INFO - Return code: 0 11:26:11 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 11:26:11 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')]} 11:26:11 INFO - https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:26:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:26:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:26:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:13 INFO - Downloaded 22848162 bytes. 11:26:13 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'] 11:26:13 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 11:26:14 INFO - Return code: 0 11:26:14 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')]} 11:26:14 INFO - https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:26:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:26:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:26:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:15 INFO - Downloaded 10834042 bytes. 11:26:15 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'] 11:26:15 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 11:26:16 INFO - Return code: 0 11:26:16 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')]} 11:26:16 INFO - https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:26:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:26:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:26:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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 11:26:17 INFO - Downloaded 54909386 bytes. 11:26:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:26:17 INFO - mkdir: /builds/slave/test/properties 11:26:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:17 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:17 INFO - Contents: 11:26:17 INFO - build_url:https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:26:18 INFO - Running post-action listener: _resource_record_post_action 11:26:18 INFO - Running post-action listener: set_extra_try_arguments 11:26:18 INFO - ##### 11:26:18 INFO - ##### Finished download-and-extract step (success) 11:26:18 INFO - ##### 11:26:18 INFO - ##### 11:26:18 INFO - ##### Running populate-webroot step. 11:26:18 INFO - ##### 11:26:18 INFO - Running pre-action listener: _resource_record_pre_action 11:26:18 INFO - Running main action method: populate_webroot 11:26:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:26:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:26:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:26:18 INFO - '--tppagecycles', 11:26:18 INFO - '7'], 11:26:18 INFO - 'tests': ['tsvgm']}}, 11:26:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:26:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:26:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['damp', 'tps']}, 11:26:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'tests': ['damp', 'tps']}, 11:26:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['dromaeo_dom']}, 11:26:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['a11yr', 11:26:18 INFO - 'ts_paint', 11:26:18 INFO - 'tpaint', 11:26:18 INFO - 'sessionrestore', 11:26:18 INFO - 'sessionrestore_no_auto_restore', 11:26:18 INFO - 'tabpaint']}, 11:26:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['tsvgx', 11:26:18 INFO - 'tsvgr_opacity', 11:26:18 INFO - 'tart', 11:26:18 INFO - 'tscrollx', 11:26:18 INFO - 'cart']}, 11:26:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:18 INFO - 'tsvgr_opacity', 11:26:18 INFO - 'tart', 11:26:18 INFO - 'tscrollx', 11:26:18 INFO - 'cart']}, 11:26:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'talos_options': ['--disable-e10s'], 11:26:18 INFO - 'tests': ['tp5o']}, 11:26:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'tests': ['tp5o']}, 11:26:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'talos_options': ['--disable-e10s', 11:26:18 INFO - '--xperf_path', 11:26:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:18 INFO - 'tests': ['tp5n']}, 11:26:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:18 INFO - 'talos_options': ['--xperf_path', 11:26:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:18 INFO - 'tests': ['tp5n']}}, 11:26:18 INFO - 'talos.zip': {'path': '', 11:26:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:26:18 INFO - Running post-action listener: _resource_record_post_action 11:26:18 INFO - ##### 11:26:18 INFO - ##### Finished populate-webroot step (success) 11:26:18 INFO - ##### 11:26:18 INFO - ##### 11:26:18 INFO - ##### Running create-virtualenv step. 11:26:18 INFO - ##### 11:26:18 INFO - Running pre-action listener: _resource_record_pre_action 11:26:18 INFO - Running main action method: create_virtualenv 11:26:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:18 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 11:26:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:26:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:18 INFO - Using real prefix '/usr' 11:26:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:18 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:20 INFO - Installing pip.................done. 11:26:20 INFO - Return code: 0 11:26:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26:20 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')]} 11:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:20 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')]} 11:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:20 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:20 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 11:26:20 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 11:26:20 INFO - Using env: {'DISPLAY': ':0', 11:26:20 INFO - 'HOME': '/home/cltbld', 11:26:20 INFO - 'LANG': 'en_US.UTF-8', 11:26:20 INFO - 'LANGUAGE': 'en_US:en', 11:26:20 INFO - 'LOGNAME': 'cltbld', 11:26:20 INFO - 'MAIL': '/var/mail/cltbld', 11:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:20 INFO - 'MOZ_NO_REMOTE': '1', 11:26:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:20 INFO - 'NO_EM_RESTART': '1', 11:26:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:20 INFO - 'PWD': '/builds/slave/test', 11:26:20 INFO - 'SHELL': '/bin/bash', 11:26:20 INFO - 'SHLVL': '1', 11:26:20 INFO - 'TERM': 'linux', 11:26:20 INFO - 'TMOUT': '86400', 11:26:20 INFO - 'USER': 'cltbld', 11:26:20 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:20 INFO - '_': '/tools/buildbot/bin/python'} 11:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:21 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 11:26:21 INFO - Cleaning up... 11:26:21 INFO - Return code: 0 11:26:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26:21 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')]} 11:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:21 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')]} 11:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:21 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:21 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 11:26:21 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 11:26:21 INFO - Using env: {'DISPLAY': ':0', 11:26:21 INFO - 'HOME': '/home/cltbld', 11:26:21 INFO - 'LANG': 'en_US.UTF-8', 11:26:21 INFO - 'LANGUAGE': 'en_US:en', 11:26:21 INFO - 'LOGNAME': 'cltbld', 11:26:21 INFO - 'MAIL': '/var/mail/cltbld', 11:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:21 INFO - 'MOZ_NO_REMOTE': '1', 11:26:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:21 INFO - 'NO_EM_RESTART': '1', 11:26:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:21 INFO - 'PWD': '/builds/slave/test', 11:26:21 INFO - 'SHELL': '/bin/bash', 11:26:21 INFO - 'SHLVL': '1', 11:26:21 INFO - 'TERM': 'linux', 11:26:21 INFO - 'TMOUT': '86400', 11:26:21 INFO - 'USER': 'cltbld', 11:26:21 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:21 INFO - '_': '/tools/buildbot/bin/python'} 11:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:21 INFO - Downloading/unpacking psutil>=3.1.1 11:26:21 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 11:26:21 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 11:26:21 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 11:26:21 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 11:26:21 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 11:26:21 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 11:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:23 INFO - Installing collected packages: psutil 11:26:23 INFO - Running setup.py install for psutil 11:26:23 INFO - building 'psutil._psutil_linux' extension 11:26:23 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 11:26:23 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 11:26:23 INFO - building 'psutil._psutil_posix' extension 11:26:23 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 11:26:23 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 11:26:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:23 INFO - Successfully installed psutil 11:26:23 INFO - Cleaning up... 11:26:23 INFO - Return code: 0 11:26:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:23 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')]} 11:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:23 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')]} 11:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:23 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:23 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 11:26:23 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 11:26:23 INFO - Using env: {'DISPLAY': ':0', 11:26:23 INFO - 'HOME': '/home/cltbld', 11:26:23 INFO - 'LANG': 'en_US.UTF-8', 11:26:23 INFO - 'LANGUAGE': 'en_US:en', 11:26:23 INFO - 'LOGNAME': 'cltbld', 11:26:23 INFO - 'MAIL': '/var/mail/cltbld', 11:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:23 INFO - 'MOZ_NO_REMOTE': '1', 11:26:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:23 INFO - 'NO_EM_RESTART': '1', 11:26:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:23 INFO - 'PWD': '/builds/slave/test', 11:26:23 INFO - 'SHELL': '/bin/bash', 11:26:23 INFO - 'SHLVL': '1', 11:26:23 INFO - 'TERM': 'linux', 11:26:23 INFO - 'TMOUT': '86400', 11:26:23 INFO - 'USER': 'cltbld', 11:26:23 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:23 INFO - '_': '/tools/buildbot/bin/python'} 11:26:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:23 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 11:26:23 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 11:26:23 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 11:26:23 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 11:26:23 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 11:26:23 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 11:26:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:25 INFO - Installing collected packages: mozsystemmonitor 11:26:25 INFO - Running setup.py install for mozsystemmonitor 11:26:26 INFO - Successfully installed mozsystemmonitor 11:26:26 INFO - Cleaning up... 11:26:26 INFO - Return code: 0 11:26:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26:26 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')]} 11:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:26 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')]} 11:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:26 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:26 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 11:26:26 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 11:26:26 INFO - Using env: {'DISPLAY': ':0', 11:26:26 INFO - 'HOME': '/home/cltbld', 11:26:26 INFO - 'LANG': 'en_US.UTF-8', 11:26:26 INFO - 'LANGUAGE': 'en_US:en', 11:26:26 INFO - 'LOGNAME': 'cltbld', 11:26:26 INFO - 'MAIL': '/var/mail/cltbld', 11:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:26 INFO - 'MOZ_NO_REMOTE': '1', 11:26:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:26 INFO - 'NO_EM_RESTART': '1', 11:26:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:26 INFO - 'PWD': '/builds/slave/test', 11:26:26 INFO - 'SHELL': '/bin/bash', 11:26:26 INFO - 'SHLVL': '1', 11:26:26 INFO - 'TERM': 'linux', 11:26:26 INFO - 'TMOUT': '86400', 11:26:26 INFO - 'USER': 'cltbld', 11:26:26 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:26 INFO - '_': '/tools/buildbot/bin/python'} 11:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:26 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:26 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 11:26:26 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 11:26:26 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 11:26:26 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 11:26:26 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 11:26:26 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 11:26:28 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 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 11:26:28 INFO - Downloading docopt-0.6.1.tar.gz 11:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:28 INFO - Running setup.py install for blobuploader 11:26:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:29 INFO - Running setup.py install for requests 11:26:29 INFO - Running setup.py install for docopt 11:26:29 INFO - Successfully installed blobuploader requests docopt 11:26:29 INFO - Cleaning up... 11:26:29 INFO - Return code: 0 11:26:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:29 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')]} 11:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:29 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')]} 11:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:29 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:29 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 11:26:29 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 11:26:29 INFO - Using env: {'DISPLAY': ':0', 11:26:29 INFO - 'HOME': '/home/cltbld', 11:26:29 INFO - 'LANG': 'en_US.UTF-8', 11:26:29 INFO - 'LANGUAGE': 'en_US:en', 11:26:29 INFO - 'LOGNAME': 'cltbld', 11:26:29 INFO - 'MAIL': '/var/mail/cltbld', 11:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:29 INFO - 'MOZ_NO_REMOTE': '1', 11:26:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:29 INFO - 'NO_EM_RESTART': '1', 11:26:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:29 INFO - 'PWD': '/builds/slave/test', 11:26:29 INFO - 'SHELL': '/bin/bash', 11:26:29 INFO - 'SHLVL': '1', 11:26:29 INFO - 'TERM': 'linux', 11:26:29 INFO - 'TMOUT': '86400', 11:26:29 INFO - 'USER': 'cltbld', 11:26:29 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:29 INFO - '_': '/tools/buildbot/bin/python'} 11:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:29 INFO - Running setup.py (path:/tmp/pip-q8L9k0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:29 INFO - Running setup.py (path:/tmp/pip-hnrKk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:29 INFO - Running setup.py (path:/tmp/pip-wRs3Lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:29 INFO - Running setup.py (path:/tmp/pip-vxa8aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:30 INFO - Running setup.py (path:/tmp/pip-3hhBvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:30 INFO - Running setup.py (path:/tmp/pip-67X8Hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:30 INFO - Running setup.py (path:/tmp/pip-3IKFdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:30 INFO - Running setup.py (path:/tmp/pip-o9MjsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:30 INFO - Running setup.py (path:/tmp/pip-kakcmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:30 INFO - Running setup.py (path:/tmp/pip-JFOUyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:30 INFO - Running setup.py (path:/tmp/pip-AJ9zBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:30 INFO - Running setup.py (path:/tmp/pip-w06vZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:30 INFO - Running setup.py (path:/tmp/pip-f2RnTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:30 INFO - Running setup.py (path:/tmp/pip-Lh74v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:30 INFO - Running setup.py (path:/tmp/pip-vw3P4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:30 INFO - Running setup.py (path:/tmp/pip-UBKyG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:30 INFO - Running setup.py (path:/tmp/pip-V17x0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:30 INFO - Running setup.py install for manifestparser 11:26:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Running setup.py install for mozcrash 11:26:31 INFO - Running setup.py install for mozdebug 11:26:31 INFO - Running setup.py install for mozdevice 11:26:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Running setup.py install for mozfile 11:26:31 INFO - Running setup.py install for mozhttpd 11:26:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Running setup.py install for mozinfo 11:26:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Running setup.py install for mozInstall 11:26:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:31 INFO - Running setup.py install for mozleak 11:26:31 INFO - Running setup.py install for mozlog 11:26:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Running setup.py install for moznetwork 11:26:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Running setup.py install for mozprocess 11:26:32 INFO - Running setup.py install for mozprofile 11:26:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Running setup.py install for mozrunner 11:26:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Running setup.py install for mozscreenshot 11:26:32 INFO - Running setup.py install for moztest 11:26:32 INFO - Running setup.py install for mozversion 11:26:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:32 INFO - Cleaning up... 11:26:32 INFO - Return code: 0 11:26:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26: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')]} 11:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:32 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:32 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 11:26:32 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 11:26:32 INFO - Using env: {'DISPLAY': ':0', 11:26:32 INFO - 'HOME': '/home/cltbld', 11:26:32 INFO - 'LANG': 'en_US.UTF-8', 11:26:32 INFO - 'LANGUAGE': 'en_US:en', 11:26:32 INFO - 'LOGNAME': 'cltbld', 11:26:32 INFO - 'MAIL': '/var/mail/cltbld', 11:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:32 INFO - 'MOZ_NO_REMOTE': '1', 11:26:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:32 INFO - 'NO_EM_RESTART': '1', 11:26:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:32 INFO - 'PWD': '/builds/slave/test', 11:26:32 INFO - 'SHELL': '/bin/bash', 11:26:32 INFO - 'SHLVL': '1', 11:26:32 INFO - 'TERM': 'linux', 11:26:32 INFO - 'TMOUT': '86400', 11:26:32 INFO - 'USER': 'cltbld', 11:26:32 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:32 INFO - '_': '/tools/buildbot/bin/python'} 11:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:33 INFO - Running setup.py (path:/tmp/pip-yoVd8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:33 INFO - Running setup.py (path:/tmp/pip-RXzDKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:33 INFO - Running setup.py (path:/tmp/pip-bNl46q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:33 INFO - Running setup.py (path:/tmp/pip-3rmWIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:33 INFO - Running setup.py (path:/tmp/pip-7jEUo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:33 INFO - Running setup.py (path:/tmp/pip-OwhJtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:33 INFO - Running setup.py (path:/tmp/pip-PrEhjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:33 INFO - Running setup.py (path:/tmp/pip-HtNo4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:33 INFO - Running setup.py (path:/tmp/pip-s8WkR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:33 INFO - Running setup.py (path:/tmp/pip-syZkZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:33 INFO - Running setup.py (path:/tmp/pip-4mMc6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:33 INFO - Running setup.py (path:/tmp/pip-9vPpmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:33 INFO - Running setup.py (path:/tmp/pip-9W7wf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:33 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)) 11:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:33 INFO - Running setup.py (path:/tmp/pip-PgWmVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:34 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)) 11:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:34 INFO - Running setup.py (path:/tmp/pip-yrOkie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:34 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)) 11:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:34 INFO - Running setup.py (path:/tmp/pip-SeAOUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:34 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)) 11:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:34 INFO - Running setup.py (path:/tmp/pip-BJ1Y9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:34 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)) 11:26:34 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)) 11:26:34 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)) 11:26:34 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)) 11:26:34 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)) 11:26:34 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)) 11:26:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:34 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 11:26:34 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 11:26:34 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 11:26:34 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 11:26:34 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 11:26:34 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 11:26:36 INFO - Downloading blessings-1.6.tar.gz 11:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:36 INFO - Installing collected packages: blessings 11:26:36 INFO - Running setup.py install for blessings 11:26:36 INFO - Successfully installed blessings 11:26:36 INFO - Cleaning up... 11:26:36 INFO - Return code: 0 11:26:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:36 INFO - Reading from file tmpfile_stdout 11:26:36 INFO - Current package versions: 11:26:36 INFO - argparse == 1.2.1 11:26:36 INFO - blessings == 1.6 11:26:36 INFO - blobuploader == 1.2.4 11:26:36 INFO - docopt == 0.6.1 11:26:36 INFO - manifestparser == 1.1 11:26:36 INFO - mozInstall == 1.12 11:26:36 INFO - mozcrash == 0.17 11:26:36 INFO - mozdebug == 0.1 11:26:36 INFO - mozdevice == 0.48 11:26:36 INFO - mozfile == 1.2 11:26:36 INFO - mozhttpd == 0.7 11:26:36 INFO - mozinfo == 0.9 11:26:36 INFO - mozleak == 0.1 11:26:36 INFO - mozlog == 3.2 11:26:36 INFO - moznetwork == 0.27 11:26:36 INFO - mozprocess == 0.22 11:26:36 INFO - mozprofile == 0.28 11:26:36 INFO - mozrunner == 6.11 11:26:36 INFO - mozscreenshot == 0.1 11:26:36 INFO - mozsystemmonitor == 0.3 11:26:36 INFO - moztest == 0.7 11:26:36 INFO - mozversion == 1.4 11:26:36 INFO - psutil == 3.1.1 11:26:36 INFO - requests == 1.2.3 11:26:36 INFO - wsgiref == 0.1.2 11:26:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:36 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')]} 11:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:36 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')]} 11:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:36 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:36 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 11:26:36 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 11:26:36 INFO - Using env: {'DISPLAY': ':0', 11:26:36 INFO - 'HOME': '/home/cltbld', 11:26:36 INFO - 'LANG': 'en_US.UTF-8', 11:26:36 INFO - 'LANGUAGE': 'en_US:en', 11:26:36 INFO - 'LOGNAME': 'cltbld', 11:26:36 INFO - 'MAIL': '/var/mail/cltbld', 11:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:36 INFO - 'MOZ_NO_REMOTE': '1', 11:26:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:36 INFO - 'NO_EM_RESTART': '1', 11:26:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:36 INFO - 'PWD': '/builds/slave/test', 11:26:36 INFO - 'SHELL': '/bin/bash', 11:26:36 INFO - 'SHLVL': '1', 11:26:36 INFO - 'TERM': 'linux', 11:26:36 INFO - 'TMOUT': '86400', 11:26:36 INFO - 'USER': 'cltbld', 11:26:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:36 INFO - '_': '/tools/buildbot/bin/python'} 11:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 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)) 11:26:36 INFO - Cleaning up... 11:26:36 INFO - Return code: 0 11:26:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:36 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')]} 11:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:36 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')]} 11:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:36 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:36 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 11:26:36 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 11:26:36 INFO - Using env: {'DISPLAY': ':0', 11:26:36 INFO - 'HOME': '/home/cltbld', 11:26:36 INFO - 'LANG': 'en_US.UTF-8', 11:26:36 INFO - 'LANGUAGE': 'en_US:en', 11:26:36 INFO - 'LOGNAME': 'cltbld', 11:26:36 INFO - 'MAIL': '/var/mail/cltbld', 11:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:36 INFO - 'MOZ_NO_REMOTE': '1', 11:26:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:36 INFO - 'NO_EM_RESTART': '1', 11:26:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:36 INFO - 'PWD': '/builds/slave/test', 11:26:36 INFO - 'SHELL': '/bin/bash', 11:26:36 INFO - 'SHLVL': '1', 11:26:36 INFO - 'TERM': 'linux', 11:26:36 INFO - 'TMOUT': '86400', 11:26:36 INFO - 'USER': 'cltbld', 11:26:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:36 INFO - '_': '/tools/buildbot/bin/python'} 11:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:37 INFO - Downloading/unpacking jsonschema 11:26:37 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 11:26:37 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 11:26:37 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 11:26:37 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 11:26:37 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 11:26:37 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 11:26:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:38 INFO - Downloading/unpacking functools32 (from jsonschema) 11:26:38 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 11:26:38 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 11:26:38 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 11:26:38 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 11:26:38 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 11:26:38 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 11:26:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:39 INFO - warning: no files found matching '*.txt' 11:26:39 INFO - no previously-included directories found matching 'build' 11:26:39 INFO - no previously-included directories found matching 'dist' 11:26:39 INFO - no previously-included directories found matching '.git*' 11:26:39 INFO - Installing collected packages: jsonschema, functools32 11:26:39 INFO - Running setup.py install for functools32 11:26:39 INFO - warning: no files found matching '*.txt' 11:26:39 INFO - no previously-included directories found matching 'build' 11:26:39 INFO - no previously-included directories found matching 'dist' 11:26:39 INFO - no previously-included directories found matching '.git*' 11:26:39 INFO - Successfully installed jsonschema functools32 11:26:39 INFO - Cleaning up... 11:26:39 INFO - Return code: 0 11:26:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:26:39 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')]} 11:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:39 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')]} 11:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:39 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 0x7f59c5bd1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c40770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, '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': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', '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 11:26:39 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 11:26:39 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 11:26:39 INFO - Using env: {'DISPLAY': ':0', 11:26:39 INFO - 'HOME': '/home/cltbld', 11:26:39 INFO - 'LANG': 'en_US.UTF-8', 11:26:39 INFO - 'LANGUAGE': 'en_US:en', 11:26:39 INFO - 'LOGNAME': 'cltbld', 11:26:39 INFO - 'MAIL': '/var/mail/cltbld', 11:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:39 INFO - 'MOZ_NO_REMOTE': '1', 11:26:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:39 INFO - 'NO_EM_RESTART': '1', 11:26:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:39 INFO - 'PWD': '/builds/slave/test', 11:26:39 INFO - 'SHELL': '/bin/bash', 11:26:39 INFO - 'SHLVL': '1', 11:26:39 INFO - 'TERM': 'linux', 11:26:39 INFO - 'TMOUT': '86400', 11:26:39 INFO - 'USER': 'cltbld', 11:26:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:39 INFO - '_': '/tools/buildbot/bin/python'} 11:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:39 INFO - Downloading/unpacking flake8 11:26:39 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 11:26:39 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 11:26:39 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 11:26:39 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 11:26:39 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 11:26:39 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 11:26:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:26:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:26:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:26:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:26:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:26:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:26:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:26:42 INFO - no previously-included directories found matching 'docs/_build' 11:26:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:26:42 INFO - Running setup.py install for pep8 11:26:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:26:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:26:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:26:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:26:42 INFO - no previously-included directories found matching 'docs/_build' 11:26:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:26:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:26:42 INFO - Cleaning up... 11:26:42 INFO - Return code: 0 11:26:42 INFO - Running post-action listener: _resource_record_post_action 11:26:42 INFO - Running post-action listener: _start_resource_monitoring 11:26:42 INFO - Starting resource monitoring. 11:26:42 INFO - ##### 11:26:42 INFO - ##### Finished create-virtualenv step (success) 11:26:42 INFO - ##### 11:26:42 INFO - ##### 11:26:42 INFO - ##### Running install step. 11:26:42 INFO - ##### 11:26:42 INFO - Running pre-action listener: _resource_record_pre_action 11:26:42 INFO - Running main action method: install 11:26:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:42 INFO - Reading from file tmpfile_stdout 11:26:42 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:42 INFO - Reading from file tmpfile_stdout 11:26:42 INFO - Output received: 11:26:42 INFO - Usage: mozinstall [options] installer 11:26:42 INFO - Options: 11:26:42 INFO - -h, --help show this help message and exit 11:26:42 INFO - -d DEST, --destination=DEST 11:26:42 INFO - Directory to install application into. [default: 11:26:42 INFO - "/builds/slave/test"] 11:26:42 INFO - --app=APP Application being installed. [default: firefox] 11:26:42 INFO - mkdir: /builds/slave/test/build/application 11:26:42 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'] 11:26:42 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 11:26:58 INFO - Reading from file tmpfile_stdout 11:26:58 INFO - Output received: 11:26:58 INFO - /builds/slave/test/build/application/firefox/firefox 11:26:58 INFO - Running post-action listener: _resource_record_post_action 11:26:58 INFO - ##### 11:26:58 INFO - ##### Finished install step (success) 11:26:58 INFO - ##### 11:26:58 INFO - ##### 11:26:58 INFO - ##### Running run-tests step. 11:26:58 INFO - ##### 11:26:58 INFO - Running pre-action listener: _resource_record_pre_action 11:26:58 INFO - Running main action method: run_tests 11:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:26:58 INFO - Python 2.7.3 11:26:58 INFO - Return code: 0 11:26:58 INFO - grabbing minidump binary from tooltool 11:26: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')]} 11:26:58 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 0x1ccf9c0>, '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 0x1cd08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:26:58 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 11:26:58 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 11:26:58 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 11:26:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:26:58 INFO - Return code: 0 11:26:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:26:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:26:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:26:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:26:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:26:59 INFO - Return code: 0 11:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 11:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 11:26:59 INFO - Using env: {'DISPLAY': ':0', 11:26:59 INFO - 'HOME': '/home/cltbld', 11:26:59 INFO - 'LANG': 'en_US.UTF-8', 11:26:59 INFO - 'LANGUAGE': 'en_US:en', 11:26:59 INFO - 'LOGNAME': 'cltbld', 11:26:59 INFO - 'MAIL': '/var/mail/cltbld', 11:26:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:59 INFO - 'MOZ_NO_REMOTE': '1', 11:26:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:59 INFO - 'NO_EM_RESTART': '1', 11:26:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:59 INFO - 'PWD': '/builds/slave/test', 11:26:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:59 INFO - 'SHELL': '/bin/bash', 11:26:59 INFO - 'SHLVL': '1', 11:26:59 INFO - 'TERM': 'linux', 11:26:59 INFO - 'TMOUT': '86400', 11:26:59 INFO - 'USER': 'cltbld', 11:26:59 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834', 11:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:59 INFO - '_': '/tools/buildbot/bin/python'} 11:26:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 11:26:59 INFO - mozversion application_buildid: 20160527094749 11:26:59 INFO - mozversion application_changeset: 545da344ffbd4cb48d171295b9084d6f6247e786 11:26:59 INFO - mozversion application_display_name: Nightly 11:26:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:26:59 INFO - mozversion application_name: Firefox 11:26:59 INFO - mozversion application_remotingname: firefox 11:26:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 11:26:59 INFO - mozversion application_vendor: Mozilla 11:26:59 INFO - mozversion application_version: 49.0a1 11:26:59 INFO - mozversion platform_buildid: 20160527094749 11:26:59 INFO - mozversion platform_changeset: 545da344ffbd4cb48d171295b9084d6f6247e786 11:26:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 11:26:59 INFO - mozversion platform_version: 49.0a1 11:26:59 INFO - using testdate: 1464373619 11:26:59 INFO - actual date: 1464373619 11:26:59 INFO - starting webserver on 'localhost:41151' 11:26:59 INFO - SUITE-START | Running 2 tests 11:26:59 INFO - TEST-START | dromaeo_css 11:26:59 INFO - operating with platform_type : linux_ 11:26:59 INFO - Initialising browser for dromaeo_css test... 11:26:59 INFO - TEST-INFO | started process 21674 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbXtjii/profile http://localhost:41151/getInfo.html) 11:27:04 INFO - PROCESS | 21674 | __metrics Screen width/height:1600/1200 11:27:04 INFO - PROCESS | 21674 | colorDepth:24 11:27:04 INFO - PROCESS | 21674 | Browser inner width/height: 1024/697 11:27:04 INFO - PROCESS | 21674 | __metrics 11:27:05 ERROR - PROCESS | 21674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:27:05 INFO - TEST-INFO | 21674: exit 0 11:27:05 INFO - Browser initialized. 11:27:05 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:05 INFO - TEST-INFO | started process 21754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbXtjii/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:17 INFO - PROCESS | 21754 | RSS: Main: 156512256 11:27:17 INFO - PROCESS | 21754 | 11:28:09 INFO - PROCESS | 21754 | 1464373689463 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] 11:30:21 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41151/tests/dromaeo/cssquery-ext.html) 11:30:22 INFO - PROCESS | 21754 | RSS: Main: 265928704 11:30:22 INFO - PROCESS | 21754 | 11:33:25 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-ext.html (next: http://localhost:41151/tests/dromaeo/cssquery-jquery.html) 11:33:25 INFO - PROCESS | 21754 | RSS: Main: 228503552 11:33:25 INFO - PROCESS | 21754 | 11:36:28 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41151/tests/dromaeo/cssquery-mootools.html) 11:36:28 INFO - PROCESS | 21754 | RSS: Main: 246554624 11:36:28 INFO - PROCESS | 21754 | 11:39:30 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41151/tests/dromaeo/cssquery-prototype.html) 11:39:31 INFO - PROCESS | 21754 | RSS: Main: 230731776 11:39:31 INFO - PROCESS | 21754 | 11:42:34 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41151/tests/dromaeo/cssquery-yui.html) 11:42:35 INFO - PROCESS | 21754 | RSS: Main: 227188736 11:42:35 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | Cycle 1(1): loaded http://localhost:41151/tests/dromaeo/cssquery-yui.html (next: http://localhost:41151/tests/dromaeo/cssquery-dojo.html) 11:45:37 INFO - PROCESS | 21754 | RSS: Main: 270016512 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | __start_tp_report 11:45:37 INFO - PROCESS | 21754 | _x_x_mozilla_page_load 11:45:37 INFO - PROCESS | 21754 | _x_x_mozilla_page_load_details 11:45:37 INFO - PROCESS | 21754 | |i|pagename|runs| 11:45:37 INFO - PROCESS | 21754 | |0;dojo.html;2690.6636670416196;2692.784577233821;2730.0035487047226;2732.6037724529956;2748.82132661377;4661.88345291367;4665.463471700756;4696.225122854734;4761.952380476198;4803.5916947059495;4725.102230576188;4752.596029337502;4804.279358096287;4817.759112044392;4840.757962101899;4321.503027151886;4336.97831510842;4353.760543170124;4446.710963787352;4465.285554311311;4351.108022855323;4421.646268298545;4449.57729015743;4509.79705913233;4565.155446242439;4479.4176757021705;4515.412996310475;4518.615917647004;4525.524819893913;4540.091981603676;4268.167707297078;4319.546447623017;4331.566843315674;4356.455443069616;4400.889977750582;4301.247281725717;4306.935395969063;4354.368616550614;4366.432363792718;4404.603585677272;4681.297805329194;4681.765911704401;4696.835610753641;4697.6241900647865;4704.976475117637;4962.702237865704;5091.210862316309;5158;5168.870778230536;5233.9738301308635;5202.895942081175;5227.634065615409;5228.816991405321;5248.606354523426;5249.028929648028;4656.674032817738;4834.6374021948495;4834.758262086952;4867.586255168315;4885.7312847793355;5168.405633352138;5250.448702886219;5250.789968401221;5265.76304066318;5323.733813309396;5103.336566246438;5129.512696293846;5148.613853960893;5149.871253218699;5152.845414637567;4999.400071991385;5185.429602743695;5193.091209038404;5241.947580524146;5250.133727934848;5149.45930677281;5161.09680805865;5161.66449180802;5194.065068287745;5207.3230480037355;5143.691378517226;5147.408048074444;5154.278401023859;5157.045946499909;5210.478952104835;4292.5063617683745;4335.241332766753;4394.472663280363;4465;4466.0398014427055;3861.691064714816;3861.826217820205;3872.0900588361715;3889.163829776555;3930;4424.80088396023;4443.133588950188;4448.866534003985;4483.820647174077;4485;5074.13739664258;5127.077126117335;5179.8446046618665;5203.427622961472;5208.374995000624;4423.73457592539;4442.911141777211;4486.035502367007;4488.708233964782;4492.191405546968;4735.881602959954;4736.526347365236;4738.19450693383;4743.549362810528;4775.6657034007985;4725.054989002282;4747.359106520576;4939.110960027229;4940.11078005948;4989.750512474435;5054.418741844588;5084.1102807009365;5085.186370180753;5088.414832294334;5130.97434512825;4790;4809.0862736080035;4818.325434439252;4820.325154478446;4867.7322747249245;4971.950280497149;4986.426560945538;5012.348394708811;5038.798448062036;5060.3168572242275;5137.998090372491;5161;5163.509466600667;5165.302684137593;5205.245239440335;5005.274235235943;5015.974920125522;5017.84946451607;5022.924656130234;5067.113255180255;4910;4948.678335908155;4993.950060499348;5002.899942001212;5021.924671130009;4926.975365123151;4945.282933974626;4958.380202474691;4979.800807967786;4981.825636102719;5082.949170508247;5083.847484575469;5084.898302033865;5107.591392688651;5139.768710408115;4799.616030717604;4817.060673168839;4871.585915197248;4877;4920.901581968412 11:45:37 INFO - PROCESS | 21754 | |1;ext.html;554.142937155829;587.1075964533909;597.6921885229106;597.7907732293696;603.7434090511531;39454;40763.59236407635;40911.38632920501;40997.59002409975;43771.3434298485;40133;40565.79717101418;40634;40745.18509629805;40777.38833917493;7570.810729731773;7715.537067775937;7922.683092676286;7929.563873986957;10070.899291007107;9943.701688949306;10020.649277275297;10022.24833137514;10023.348482348623;10031.047050530187;10360.481975901175;10447.582096716122;10451.163906887468;10453.947730261338;10456.96089262594;7618.619069046581;7869.331106855924;7884.093329267151;10213.080822725917;10269;9945.950270248675;10066.446345450997;10192.745181370525;10196.133328666998;10203.642872499428;38716.41925371122;38756.80621596902;39360.606393936265;39422.80288598567;39491.40762888559;3117;3126.7342275906576;3625.29306785177;3632.945505817415;3747.981260093682;28272.85863570669;30600.54099188514;30725.385492290254;31026.689733102605;32169;3467.09855437588;3487.137769223908;3497.790132592027;3604.9639503605154;3676.8345424455697;264667;267006.9999999981;267169;267317;267457;57818.4218157813;59038.70480647569;59063.704681476316;59160.11259831106;59261.40738592645;34018;35182.472262915566;35202;37247.441288380694;37281.81359093187;15391.923040384727;15516.301766420538;15519.146446945526;15538.766918496232;15554.377824886878;10376.844347334796;11453.770924581462;11690.766184676258;11713.765724685629;11956.222845515187;19482.64783091661;19594;19640.508987275432;19737.605247895248;19778.10998505071;7433.516821406592;7502.286368247752;7614.543127412373;7764.184760600168;7794.298513133732;770.7418014964937;870.4472659860908;924.6578765856542;944.6457578408097;991.4794732765356;27859.303517412227;28082.85958570194;28369;28848.278793030342;29047.56428653572;953.7663272498282;998.0817647764075;1009.9242556808268;1017.8829434614966;1029.0378496106016;14605.78091328631;15069.623259418602;15080.321385537678;15123.470678526199;15417.691646167013;404474;409327;411660.65018049505;412722;412954;50025.24962125571;50437;50534.24198637023;50625;50648.454608177075;1108.894655007773;1146.633077415219;1210.9757804843853;1218.1107791312215;1235.5922545559943;27026.32434189161;28846.278843029093;29733;29736.405271894873;29759.404811904074;42020.36969445522;42244.577554224066;42357;42502.36246456244;42535;303859;306223;306894;307124;307508;22407.103715851834;24871.50256994886;25444.236672899842;25664.23007309784;25962.610560841225;41803;42207.788961055;42517.832412549906;42781;43187.56812431961;35486.82256588804;35986.28027439489;36760.44859327059;36769.08077298089;37164;35677;35761.431411530815;36261.81869090637;36304.81847590745;36565.08587285339;32542.51186232161;32633.836830815693;35291.29417411586;35834.10414739652;36452;31219.84390078035;32493.675063249066;32578.51132232971;32720;32840.178995525304;24954.500909981336;25212.24363269105;25417.61873571935;26332.8683356582;27371.72628273692 11:45:37 INFO - PROCESS | 21754 | |2;jquery.html;359.51824555096175;360.7186394612202;363.8308186693189;380.1750202061526;380.4388526922791;15821;16524.917375413104;16569.25438355274;16929.49211523653;16947.57631059224;14129.576112716635;14271;14293.999420040602;14317.284135793194;14317.928410357934;4889.168841296988;4955.925661115086;5128.871778205537;5143.691378517282;5144.742762861861;3638.5633723953165;3824.9043773905737;4986.75066246688;5525.502704756571;5840.474357307841;5875.500582450497;5884.411558844103;5886.970565147169;5901.881962360751;5904;5754.482096611283;5816;5897.439743224409;5901.734421951022;5935.910961335583;3333.4999750037446;4723.622110231173;5070.543651071422;5079.593632509391;5174.094533456668;19105.904470477697;19735.80264197354;19784;20074;20108.39674809758;1902.7050807124917;1929.5754933914516;1953.8632295739303;2090.6654935210295;2096.371088673407;12656.55702050433;12717.682057948532;12750.681232969157;13063.934680326538;13076;1736.782902137233;1763.8765286429827;1803.2156012134762;1808.8010318864915;1837.0814592703648;62206.68896655533;62220.37779622191;62714;63528.68235658838;63658.68170659117;8670.91329086701;8830.293576513863;9022.323325750594;9062.410943288665;9133.49765762876;12162.391880406121;12465.563705270453;12478.937605311916;12483.750324993449;12493.875061249453;6890.276520965327;6898.724051037902;6962.477814163958;7025.40284075849;7067.752628657972;6678;6704.094947182166;6766.49251306154;6868.793936181923;6898.379145876896;5782.681952492653;5817.272840894888;5817.650940943557;5867.911981320283;5876.44173803488;1521.8630323270959;1622.5375767906326;1643.958901027484;1690.1633691322875;1769.4426255729404;894.6287290774255;905.1672461335587;908.9318301127441;992.3004281981185;1023.902729240721;15859;16776;17173.398931037354;17741.556461088578;18099.819001809814;870.5125129927135;876.2770714160843;883.1080608585247;927.1933417926447;959.856021596766;9642.855357169648;10185.134263587677;10219.080282774587;10224.028717271847;10256;382830;383817.08091459924;383932;384842;391520;73976;75099;75988.24011759923;76644.61677691687;77906;952.2429668413628;956.1442508954447;964.62379671928;968.7190714692802;985.5564995752084;15185.544433667008;15239.3904243829;15263.694726105194;15822.841771582136;15833.366665333719;31764.682353176173;32423.865164719122;33594.32811343808;34424.13939651529;34686.65313346935;47946.76026619845;48248;49596.75201623969;49609;49959.25061124159;7130.465215108784;7157.892631610426;7186.425085993003;7315.231900650355;7366.594837284001;11240.887591123985;11357.772844543228;11361.204715669825;11437.542498299976;11534.884651153381;9579.90420095818;9649.469279189712;9651.855222171533;9915.25635577349;9971.900280997388;9735.464549449849;9873.851892221479;9971.102600765966;10022.448765317977;10132.442715650415;9638.614455421704;9663.516824158905;9887.110160085627;9961.950190249003;10002.799944001225;6957.930420695729;7018.894716579358;7030.753923612649;7102.715891364288;7123.679434425567;9698.224142068477;9845.113939745457;9877.160441362565;9949;9962.252831037846 11:45:37 INFO - PROCESS | 21754 | |3;mootools.html;1299.5646458436424;1429.3853642933539;1431.7566013777657;1582.9129397883112;1778.279796682344;6698;7522.097348318202;7751.418643601724;7763.223677632234;7969.601519924009;7665;7864.567448790314;7866;7884.605769711506;7894.092179399371;2181.5309708412688;2208.9668654970187;2212.126210146991;2212.7565967743535;2215.3575463115676;2173.717416735822;2211.4139752965475;2235.0724449353497;2235.3740952533235;2239.305815197286;2182.334388011656;2203.6804663323887;2212.037763572851;2212.4468882779306;2215.556888622282;2125.2774056820676;2126.861753985986;2132.7973842485017;2133.328001679466;2133.775953524889;2093.6964140199584;2128.372130221588;2129.084493667722;2129.9787002129933;2130.360891732474;7591.924080759177;7690;7764.650590723431;7787.026621672291;7801.804954876116;4646.4424269087585;4771.928421073687;4775.068861571995;4777.235642297251;4777.28340748887;2907.592936988823;2923.824570525754;2969.376430949482;2999.2651800309063;3020.592220050287;887.9778005549848;907.6505545365083;917.4816228830756;924.8658944453084;942.1144124522995;10448.52981615829;10482.056614904695;10489.160867130611;10521.684349469528;10550.366977981268;3908.355121404936;4037.5154981401834;4085.20338533989;4099.200655872136;4107.219628270619;8306.70926517581;8372.832543349221;8383.622736976851;8389.07720150783;8400.789980250544;4433.645308375322;4435.733855968588;4438.134563760036;4441.467023957146;4449.555044495524;3754.81225938702;4127.711060225755;4141.295979683461;4148.066684995852;4325.848595299212;3434.5706786651667;3476.965230347664;3508.4035713928693;3545.8758943436856;3669.6697297243377;1034.9793004139874;1043.400044974143;1044.6970378590277;1051.3376572759112;1053.3100818963592;847.0470720439506;855.6449073634496;856.1010938514535;858.051852702767;863.447393668053;4960.206366981266;5023.09584274834;5100.081985242692;5121;5162.870928226749;898.4788822482944;932.8693982842408;947.052947052947;953.8903026151943;991.2912267728609;3459.9481007784902;3540.168060505779;3599.046252743025;3624.9637503625154;3648.1426864686778;9229.400088994194;9454.290928180413;9525.523723813782;9599.664011759554;9640.517974101267;9525.714228573153;9532.332736708364;9573.18627916635;9592.280578956606;9631.133198012212;6884.759033433906;7271.454640901953;7396.630168491662;7397.37122344596;7413.8146546336775;5171.534561889448;5210.088234559013;5232.3459567554055;5363.222332761806;5438.18427235902;8242.17578242171;8368.20502052304;8633.223009929137;8633.69782057625;8653.870191947251;8357.66569337244;8703;8704.477731336141;8710.69512567057;8762.386632935633;6066.636001839904;6187.3503282154725;6188.195534580475;6674.365935236145;6809.829754255985;6591.406773390418;6772.221194562689;7161.248068952893;7229.963850180716;7422.665980030803;6610.4050635443045;6794;6832.316768323128;6833.4191593715095;6944.652767361713;6704.094947182068;6723.663816809238;6724.831879202863;6808.5914845109455;6808.7276508939085;6007.579469437097;6033.818985430444;6085.543584231112;6087.878242435215;6098.634081955097;4338.7830608470085;4339.934900976425;4404.7357158570585;4434.955650443454;4436.245838207577;4570.154521413549;4575.542445755398;4580.312953057068;4583.610393116489;4586.403767510203 11:45:37 INFO - PROCESS | 21754 | |4;prototype.html;288.62478777589126;288.92199106241316;292.09450702821255;294.6228827101311;296.1677685703175;4805.303231031498;4870.878228044301;4882.6093912487;4906.68106573073;4906.779194936227;4778.784954677039;4793.400824896888;4847.175980083385;4853.247746599274;4883.536064073916;3556.199855032616;3689.815509224528;3694.9445758313645;3772.2832661794173;3808;3726.7763934164036;3759.229357981621;3778.8110594470168;3796.1458671798764;3797.316483033053;3531.876384326549;3747.4940882980723;3749.737518373715;3750.324941510527;3762.680172185354;3590.910227244314;3746.2132952080096;3760.8683696070507;3772.2078363543687;3772.2266935278203;3297.225152089257;3299.950500742491;3308.2721801203697;3314.9171270718184;3318.452455344865;4584.564466375689;4601.516840731709;4609.124266389409;4635.447125213058;4636.884077898046;4038.3846461415374;4235.470566179228;4235.597618226264;4296.140771845643;4309.418228539138;5038.823641172578;5046.5205805448795;5054.494550544916;5062.873428164288;5070.366204224472;3536.6993805526563;3541.6281290464644;3546.5744110706623;3548.6628770266784;3550.254446566224;5114.488551144893;5295.443978382253;5312.1766126250495;5339.252504649352;5340;3551.5438670145118;3560.537130173113;3580.803055831902;3582.5342705448124;3587.318409502186;3595.352836489457;3617.8603739821874;3618;3626.854925802991;3636.654517820803;4175.91648167035;4178.310578754532;4208.421841809321;4225.556316586775;4233.364995250737;4942.184539551005;5032.314174751426;5053.974730126326;5068.352785344704;5122.667026643257;1243.8742937641448;1252.7557126360455;1256.4220458589152;1262.3561983388354;1266.1390254626942;780.3718007004416;801.4790386248985;838.9680692747833;844.5650489997656;845.8392905347964;4427.535108813592;4436.866893993185;4444.711033800162;4450.46594408673;4468.642508599304;5082.33929589151;5121.692698438105;5126.436092029874;5130.615203859726;5141.845744627667;4248.660107191417;4393.7152680605905;4396.956030439655;4405.383246345515;4420.668449866273;2723.338763354352;2734.371094648242;2741.4105967217142;2758.1311886755607;2758.3517873299757;4894.388201474816;4932.161532539477;4946.282788995575;4946.505349465025;4958.9504104959215;5043.814703544664;5253.44838791929;5262.842114736564;5280.9207861882105;5291.973540132275;4905.479301416501;4905.533974272438;4928.433230178575;4932.90134197314;4937.21004639256;5129.076766182123;5155.355580552431;5167.974160129175;5174;5175.430702622633;4850.951490485191;5066.834628035499;5092.363454568179;5169.689818610745;5184.274201611702;4964.280179374043;4966.379202599675;4967.875803104951;5014.122528557561;5016.297718319511;3531.205478767257;3571.5714114306447;3580.1407662160373;3603.747737658339;3612.9096772580897;5154;5244.239585260039;5251.947480525146;5258.290130832289;5275.762590683506;5030.245463180552;5046.646734728533;5099.643024988215;5110.540051395392;5122.923156152735;5099.515546023122;5101.7704203310195;5115.974420127875;5131.640785145004;5137.306463627512;4745.406824146981;4863.4650188479945;4906.754662266943;5011.899762004812;5046.394432668103;4936.222959734421;4950.430700469492;4952.504749525018;4960.47914968923;4967.826126085714;4820.542048505386;4997.9750101249265;5001.449550639313;5012.498750124958;5015.1975683890405 11:45:37 INFO - PROCESS | 21754 | |5;yui.html;690.3027941778804;703.9190493093296;735.9190489046207;736.4035131543446;745.8471013442245;11697.3566453845;12600.369981500911;12618.747625047494;12622.24266544008;12622.558210462636;12135;12158.27050376978;12170.148089633729;12171.756564868698;12183.269003859774;617.2315467243288;751.868423025971;787.0555333599675;787.8778789287669;791.8733002719566;786.268770043859;789.7433334166419;792.2315354106507;792.8216151365925;793.6349279331514;655.931127231641;837.401258100458;838.2078935406043;838.9496630202208;839.4543546694666;798.9800254993613;825.4923222218292;829.8423299573062;831.3058596072257;831.4553967151526;608.1971797227661;608.4189598933008;608.89344364736;609.2049874913237;609.3906093906094;10625.574977000912;10668.14654827612;10680.679579612624;10685.252032357737;10745.946270268676;11154.498047587878;11163.49764260602;11343.716407089807;11470.197086203969;11779.646610601696;10308.381497110122;10320.638777642747;10420.791584168273;10595.84106238407;10657.573697052108;3828.559715632682;3861.9613803861885;3883.825227864753;3924.3917192835156;3986.720929534934;43321.350179747016;43888.780556097336;45264.99999999967;45286.54713452856;45317.54682453166;9147.725568232963;9150.801989910991;9360.391574547633;9441.858372124423;9565.61737530491;16682.749758753627;16687.165641717864;16756.07841568701;16937.32250709958;16975.830241697673;6352.205974253218;6407.647579383179;6418.069379939882;6503.967480162569;6557.278699343068;3152.3064925716308;3328.9500657490157;3411.5053317269353;3428.2629234714655;3459.619441861393;2626.7898568114506;2687.9596806047925;2690.421559364746;2703.162019773876;2706.323419145214;420.93096057986844;507.2467385931888;536.2171230004237;536.2947723743265;551.8427248234315;565.7143142712986;568.1335962656981;591.249113625693;603.4026313949158;619.7490016543308;7306.59813710251;7540;7662.540247585162;7833.532457188417;7862.135165131831;487.76831005272214;580.2659635561018;596.6023496189162;604.5949214026613;619.9194104766351;3654.6528079832374;3676.7242456815848;3700.9814950925074;3752.9249415011545;3781.262653782541;55530.72234638801;57483;57575.71212143912;57656.71171644199;58307.41692583105;42400.36399454011;42964.925876853325;44383;47442.76278618585;51975;45108.3233751494;45377.31934020992;45542.240564865526;46123.53876461193;46168;8542.82914341722;8546.401751877309;8629.39594228398;8657.61040753155;8710.474136431718;30801.845990769903;31161.688383115878;31670.366592668477;31789.20526986844;32425;37228.06929826667;38131.80934095312;39791.80104099461;39814.60185398109;40089.59910400859;10739.409332486475;10765.730856728644;10865.293755905841;10917.181211409335;10925;8111.594420279081;8702.608382622671;9436.292278079034;9795.265355098254;12173.817392738938;9069.727908162766;9070.909290907006;9104.544772761468;9289.117533834275;9300.488473133772;8691;8793.692220772242;8813.515256660688;8814.779630509289;9211.355205135576;8272.131426200162;8435.156484351515;8701.695440659547;8945.73162805117;9082.818343633222;7890.329322007694;7934.880976785237;8190.401141130182;8226.712065077694;8244.093149753402;7753;8171.305439037511;8306.127856574973;8306.499701848683;8403.327733781405 11:45:37 INFO - PROCESS | 21754 | __end_tp_report 11:45:37 INFO - PROCESS | 21754 | __start_cc_report 11:45:37 INFO - PROCESS | 21754 | _x_x_mozilla_cycle_collect,1063 11:45:37 INFO - PROCESS | 21754 | __end_cc_report 11:45:37 INFO - PROCESS | 21754 | __startTimestamp1464374737867__endTimestamp 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | ------- Summary: start ------- 11:45:37 INFO - PROCESS | 21754 | Number of tests: 6 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#0] dojo.html Cycles:165 Average:4757.79 Median:4867.73 stddev:494.28 (10.2%) stddev-sans-first:468.44 11:45:37 INFO - PROCESS | 21754 | Values: 2690.7 2692.8 2730.0 2732.6 2748.8 4661.9 4665.5 4696.2 4762.0 4803.6 4725.1 4752.6 4804.3 4817.8 4840.8 4321.5 4337.0 4353.8 4446.7 4465.3 4351.1 4421.6 4449.6 4509.8 4565.2 4479.4 4515.4 4518.6 4525.5 4540.1 4268.2 4319.5 4331.6 4356.5 4400.9 4301.2 4306.9 4354.4 4366.4 4404.6 4681.3 4681.8 4696.8 4697.6 4705.0 4962.7 5091.2 5158.0 5168.9 5234.0 5202.9 5227.6 5228.8 5248.6 5249.0 4656.7 4834.6 4834.8 4867.6 4885.7 5168.4 5250.4 5250.8 5265.8 5323.7 5103.3 5129.5 5148.6 5149.9 5152.8 4999.4 5185.4 5193.1 5241.9 5250.1 5149.5 5161.1 5161.7 5194.1 5207.3 5143.7 5147.4 5154.3 5157.0 5210.5 4292.5 4335.2 4394.5 4465.0 4466.0 3861.7 3861.8 3872.1 3889.2 3930.0 4424.8 4443.1 4448.9 4483.8 4485.0 5074.1 5127.1 5179.8 5203.4 5208.4 4423.7 4442.9 4486.0 4488.7 4492.2 4735.9 4736.5 4738.2 4743.5 4775.7 4725.1 4747.4 4939.1 4940.1 4989.8 5054.4 5084.1 5085.2 5088.4 5131.0 4790.0 4809.1 4818.3 4820.3 4867.7 4972.0 4986.4 5012.3 5038.8 5060.3 5138.0 5161.0 5163.5 5165.3 5205.2 5005.3 5016.0 5017.8 5022.9 5067.1 4910.0 4948.7 4994.0 5002.9 5021.9 4927.0 4945.3 4958.4 4979.8 4981.8 5082.9 5083.8 5084.9 5107.6 5139.8 4799.6 4817.1 4871.6 4877.0 4920.9 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#1] ext.html Cycles:180 Average:48329.08 Median:3492.46 stddev:87682.88 (2510.6%) stddev-sans-first:87855.48 11:45:37 INFO - PROCESS | 21754 | Values: 554.1 587.1 597.7 597.8 603.7 39454.0 40763.6 40911.4 40997.6 43771.3 40133.0 40565.8 40634.0 40745.2 40777.4 7570.8 7715.5 7922.7 7929.6 10070.9 9943.7 10020.6 10022.2 10023.3 10031.0 10360.5 10447.6 10451.2 10453.9 10457.0 7618.6 7869.3 7884.1 10213.1 10269.0 9946.0 10066.4 10192.7 10196.1 10203.6 38716.4 38756.8 39360.6 39422.8 39491.4 3117.0 3126.7 3625.3 3632.9 3748.0 28272.9 30600.5 30725.4 31026.7 32169.0 3467.1 3487.1 3497.8 3605.0 3676.8 264667.0 267007.0 267169.0 267317.0 267457.0 57818.4 59038.7 59063.7 59160.1 59261.4 34018.0 35182.5 35202.0 37247.4 37281.8 15391.9 15516.3 15519.1 15538.8 15554.4 10376.8 11453.8 11690.8 11713.8 11956.2 19482.6 19594.0 19640.5 19737.6 19778.1 7433.5 7502.3 7614.5 7764.2 7794.3 770.7 870.4 924.7 944.6 991.5 27859.3 28082.9 28369.0 28848.3 29047.6 953.8 998.1 1009.9 1017.9 1029.0 14605.8 15069.6 15080.3 15123.5 15417.7 404474.0 409327.0 411660.7 412722.0 412954.0 50025.2 50437.0 50534.2 50625.0 50648.5 1108.9 1146.6 1211.0 1218.1 1235.6 27026.3 28846.3 29733.0 29736.4 29759.4 42020.4 42244.6 42357.0 42502.4 42535.0 303859.0 306223.0 306894.0 307124.0 307508.0 22407.1 24871.5 25444.2 25664.2 25962.6 41803.0 42207.8 42517.8 42781.0 43187.6 35486.8 35986.3 36760.4 36769.1 37164.0 35677.0 35761.4 36261.8 36304.8 36565.1 32542.5 32633.8 35291.3 35834.1 36452.0 31219.8 32493.7 32578.5 32720.0 32840.2 24954.5 25212.2 25417.6 26332.9 27371.7 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#2] jquery.html Cycles:180 Average:23712.20 Median:5075.07 stddev:63496.79 (1251.2%) stddev-sans-first:63650.70 11:45:37 INFO - PROCESS | 21754 | Values: 359.5 360.7 363.8 380.2 380.4 15821.0 16524.9 16569.3 16929.5 16947.6 14129.6 14271.0 14294.0 14317.3 14317.9 4889.2 4955.9 5128.9 5143.7 5144.7 3638.6 3824.9 4986.8 5525.5 5840.5 5875.5 5884.4 5887.0 5901.9 5904.0 5754.5 5816.0 5897.4 5901.7 5935.9 3333.5 4723.6 5070.5 5079.6 5174.1 19105.9 19735.8 19784.0 20074.0 20108.4 1902.7 1929.6 1953.9 2090.7 2096.4 12656.6 12717.7 12750.7 13063.9 13076.0 1736.8 1763.9 1803.2 1808.8 1837.1 62206.7 62220.4 62714.0 63528.7 63658.7 8670.9 8830.3 9022.3 9062.4 9133.5 12162.4 12465.6 12478.9 12483.8 12493.9 6890.3 6898.7 6962.5 7025.4 7067.8 6678.0 6704.1 6766.5 6868.8 6898.4 5782.7 5817.3 5817.7 5867.9 5876.4 1521.9 1622.5 1644.0 1690.2 1769.4 894.6 905.2 908.9 992.3 1023.9 15859.0 16776.0 17173.4 17741.6 18099.8 870.5 876.3 883.1 927.2 959.9 9642.9 10185.1 10219.1 10224.0 10256.0 382830.0 383817.1 383932.0 384842.0 391520.0 73976.0 75099.0 75988.2 76644.6 77906.0 952.2 956.1 964.6 968.7 985.6 15185.5 15239.4 15263.7 15822.8 15833.4 31764.7 32423.9 33594.3 34424.1 34686.7 47946.8 48248.0 49596.8 49609.0 49959.3 7130.5 7157.9 7186.4 7315.2 7366.6 11240.9 11357.8 11361.2 11437.5 11534.9 9579.9 9649.5 9651.9 9915.3 9971.9 9735.5 9873.9 9971.1 10022.4 10132.4 9638.6 9663.5 9887.1 9962.0 10002.8 6957.9 7018.9 7030.8 7102.7 7123.7 9698.2 9845.1 9877.2 9949.0 9962.3 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#3] mootools.html Cycles:180 Average:5038.28 Median:5190.81 stddev:2789.28 (53.7%) stddev-sans-first:2782.95 11:45:37 INFO - PROCESS | 21754 | Values: 1299.6 1429.4 1431.8 1582.9 1778.3 6698.0 7522.1 7751.4 7763.2 7969.6 7665.0 7864.6 7866.0 7884.6 7894.1 2181.5 2209.0 2212.1 2212.8 2215.4 2173.7 2211.4 2235.1 2235.4 2239.3 2182.3 2203.7 2212.0 2212.4 2215.6 2125.3 2126.9 2132.8 2133.3 2133.8 2093.7 2128.4 2129.1 2130.0 2130.4 7591.9 7690.0 7764.7 7787.0 7801.8 4646.4 4771.9 4775.1 4777.2 4777.3 2907.6 2923.8 2969.4 2999.3 3020.6 888.0 907.7 917.5 924.9 942.1 10448.5 10482.1 10489.2 10521.7 10550.4 3908.4 4037.5 4085.2 4099.2 4107.2 8306.7 8372.8 8383.6 8389.1 8400.8 4433.6 4435.7 4438.1 4441.5 4449.6 3754.8 4127.7 4141.3 4148.1 4325.8 3434.6 3477.0 3508.4 3545.9 3669.7 1035.0 1043.4 1044.7 1051.3 1053.3 847.0 855.6 856.1 858.1 863.4 4960.2 5023.1 5100.1 5121.0 5162.9 898.5 932.9 947.1 953.9 991.3 3459.9 3540.2 3599.0 3625.0 3648.1 9229.4 9454.3 9525.5 9599.7 9640.5 9525.7 9532.3 9573.2 9592.3 9631.1 6884.8 7271.5 7396.6 7397.4 7413.8 5171.5 5210.1 5232.3 5363.2 5438.2 8242.2 8368.2 8633.2 8633.7 8653.9 8357.7 8703.0 8704.5 8710.7 8762.4 6066.6 6187.4 6188.2 6674.4 6809.8 6591.4 6772.2 7161.2 7230.0 7422.7 6610.4 6794.0 6832.3 6833.4 6944.7 6704.1 6723.7 6724.8 6808.6 6808.7 6007.6 6033.8 6085.5 6087.9 6098.6 4338.8 4339.9 4404.7 4435.0 4436.2 4570.2 4575.5 4580.3 4583.6 4586.4 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#4] prototype.html Cycles:180 Average:4174.78 Median:4849.06 stddev:1229.67 (25.4%) stddev-sans-first:1198.02 11:45:37 INFO - PROCESS | 21754 | Values: 288.6 288.9 292.1 294.6 296.2 4805.3 4870.9 4882.6 4906.7 4906.8 4778.8 4793.4 4847.2 4853.2 4883.5 3556.2 3689.8 3694.9 3772.3 3808.0 3726.8 3759.2 3778.8 3796.1 3797.3 3531.9 3747.5 3749.7 3750.3 3762.7 3590.9 3746.2 3760.9 3772.2 3772.2 3297.2 3300.0 3308.3 3314.9 3318.5 4584.6 4601.5 4609.1 4635.4 4636.9 4038.4 4235.5 4235.6 4296.1 4309.4 5038.8 5046.5 5054.5 5062.9 5070.4 3536.7 3541.6 3546.6 3548.7 3550.3 5114.5 5295.4 5312.2 5339.3 5340.0 3551.5 3560.5 3580.8 3582.5 3587.3 3595.4 3617.9 3618.0 3626.9 3636.7 4175.9 4178.3 4208.4 4225.6 4233.4 4942.2 5032.3 5054.0 5068.4 5122.7 1243.9 1252.8 1256.4 1262.4 1266.1 780.4 801.5 839.0 844.6 845.8 4427.5 4436.9 4444.7 4450.5 4468.6 5082.3 5121.7 5126.4 5130.6 5141.8 4248.7 4393.7 4397.0 4405.4 4420.7 2723.3 2734.4 2741.4 2758.1 2758.4 4894.4 4932.2 4946.3 4946.5 4959.0 5043.8 5253.4 5262.8 5280.9 5292.0 4905.5 4905.5 4928.4 4932.9 4937.2 5129.1 5155.4 5168.0 5174.0 5175.4 4851.0 5066.8 5092.4 5169.7 5184.3 4964.3 4966.4 4967.9 5014.1 5016.3 3531.2 3571.6 3580.1 3603.7 3612.9 5154.0 5244.2 5251.9 5258.3 5275.8 5030.2 5046.6 5099.6 5110.5 5122.9 5099.5 5101.8 5116.0 5131.6 5137.3 4745.4 4863.5 4906.8 5011.9 5046.4 4936.2 4950.4 4952.5 4960.5 4967.8 4820.5 4998.0 5001.4 5012.5 5015.2 11:45:37 INFO - PROCESS | 21754 | 11:45:37 INFO - PROCESS | 21754 | [#5] yui.html Cycles:180 Average:12591.25 Median:57529.36 stddev:15063.03 (26.2%) stddev-sans-first:15078.78 11:45:37 INFO - PROCESS | 21754 | Values: 690.3 703.9 735.9 736.4 745.8 11697.4 12600.4 12618.7 12622.2 12622.6 12135.0 12158.3 12170.1 12171.8 12183.3 617.2 751.9 787.1 787.9 791.9 786.3 789.7 792.2 792.8 793.6 655.9 837.4 838.2 838.9 839.5 799.0 825.5 829.8 831.3 831.5 608.2 608.4 608.9 609.2 609.4 10625.6 10668.1 10680.7 10685.3 10745.9 11154.5 11163.5 11343.7 11470.2 11779.6 10308.4 10320.6 10420.8 10595.8 10657.6 3828.6 3862.0 3883.8 3924.4 3986.7 43321.4 43888.8 45265.0 45286.5 45317.5 9147.7 9150.8 9360.4 9441.9 9565.6 16682.7 16687.2 16756.1 16937.3 16975.8 6352.2 6407.6 6418.1 6504.0 6557.3 3152.3 3329.0 3411.5 3428.3 3459.6 2626.8 2688.0 2690.4 2703.2 2706.3 420.9 507.2 536.2 536.3 551.8 565.7 568.1 591.2 603.4 619.7 7306.6 7540.0 7662.5 7833.5 7862.1 487.8 580.3 596.6 604.6 619.9 3654.7 3676.7 3701.0 3752.9 3781.3 55530.7 57483.0 57575.7 57656.7 58307.4 42400.4 42964.9 44383.0 47442.8 51975.0 45108.3 45377.3 45542.2 46123.5 46168.0 8542.8 8546.4 8629.4 8657.6 8710.5 30801.8 31161.7 31670.4 31789.2 32425.0 37228.1 38131.8 39791.8 39814.6 40089.6 10739.4 10765.7 10865.3 10917.2 10925.0 8111.6 8702.6 9436.3 9795.3 12173.8 9069.7 9070.9 9104.5 9289.1 9300.5 8691.0 8793.7 8813.5 8814.8 9211.4 8272.1 8435.2 8701.7 8945.7 9082.8 7890.3 7934.9 8190.4 8226.7 8244.1 7753.0 8171.3 8306.1 8306.5 8403.3 11:45:37 INFO - PROCESS | 21754 | -------- Summary: end -------- 11:45:37 INFO - PROCESS | 21754 | 11:45:38 INFO - TEST-INFO | 21754: exit 0 11:45:38 INFO - TEST-OK | dromaeo_css | took 1118787ms 11:45:38 INFO - TEST-START | kraken 11:45:38 INFO - operating with platform_type : linux_ 11:45:38 INFO - Initialising browser for kraken test... 11:45:38 INFO - TEST-INFO | started process 21930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb1yC1n/profile http://localhost:41151/getInfo.html) 11:45:42 INFO - PROCESS | 21930 | __metrics Screen width/height:1600/1200 11:45:42 INFO - PROCESS | 21930 | colorDepth:24 11:45:42 INFO - PROCESS | 21930 | Browser inner width/height: 1024/697 11:45:42 INFO - PROCESS | 21930 | __metrics 11:45:44 ERROR - PROCESS | 21930 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:45:44 INFO - TEST-INFO | 21930: exit 0 11:45:44 INFO - Browser initialized. 11:45:44 INFO - Running cycle 1/1 for kraken test... 11:45:44 INFO - TEST-INFO | started process 22012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb1yC1n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:56 INFO - PROCESS | 22012 | RSS: Main: 156651520 11:45:56 INFO - PROCESS | 22012 | 11:45:59 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41151/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:00 INFO - PROCESS | 22012 | RSS: Main: 212164608 11:46:00 INFO - PROCESS | 22012 | 11:46:03 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41151/tests/kraken/driver.html?testName=audio-dft) 11:46:04 INFO - PROCESS | 22012 | RSS: Main: 200142848 11:46:04 INFO - PROCESS | 22012 | 11:46:07 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41151/tests/kraken/driver.html?testName=audio-fft) 11:46:07 INFO - PROCESS | 22012 | RSS: Main: 269840384 11:46:07 INFO - PROCESS | 22012 | 11:46:10 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41151/tests/kraken/driver.html?testName=audio-oscillator) 11:46:10 INFO - PROCESS | 22012 | RSS: Main: 178319360 11:46:10 INFO - PROCESS | 22012 | 11:46:12 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41151/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:13 INFO - PROCESS | 22012 | RSS: Main: 183144448 11:46:13 INFO - PROCESS | 22012 | 11:46:16 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41151/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:17 INFO - PROCESS | 22012 | RSS: Main: 218742784 11:46:17 INFO - PROCESS | 22012 | 11:46:20 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41151/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:21 INFO - PROCESS | 22012 | RSS: Main: 216682496 11:46:21 INFO - PROCESS | 22012 | 11:46:24 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41151/tests/kraken/driver.html?testName=json-parse-financial) 11:46:24 INFO - PROCESS | 22012 | RSS: Main: 217440256 11:46:24 INFO - PROCESS | 22012 | 11:46:26 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41151/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:27 INFO - PROCESS | 22012 | RSS: Main: 204517376 11:46:27 INFO - PROCESS | 22012 | 11:46:29 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:29 INFO - PROCESS | 22012 | RSS: Main: 208928768 11:46:29 INFO - PROCESS | 22012 | 11:46:31 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:32 INFO - PROCESS | 22012 | RSS: Main: 220131328 11:46:32 INFO - PROCESS | 22012 | 11:46:35 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:36 INFO - PROCESS | 22012 | RSS: Main: 226574336 11:46:36 INFO - PROCESS | 22012 | 11:46:38 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:39 INFO - PROCESS | 22012 | RSS: Main: 223252480 11:46:39 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | Cycle 1(1): loaded http://localhost:41151/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41151/tests/kraken/driver.html?testName=ai-astar) 11:46:41 INFO - PROCESS | 22012 | RSS: Main: 268484608 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | __start_tp_report 11:46:41 INFO - PROCESS | 22012 | _x_x_mozilla_page_load 11:46:41 INFO - PROCESS | 22012 | _x_x_mozilla_page_load_details 11:46:41 INFO - PROCESS | 22012 | |i|pagename|runs| 11:46:41 INFO - PROCESS | 22012 | |0;ai-astar;113;108;113;113;112;113;113;112;113;113 11:46:41 INFO - PROCESS | 22012 | |1;audio-beat-detection;162;132;132;185;133;142;162;137;175;129 11:46:41 INFO - PROCESS | 22012 | |2;audio-dft;161;162;162;160;159;161;163;165;182;161 11:46:41 INFO - PROCESS | 22012 | |3;audio-fft;74;77;75;105;75;76;76;75;97;75 11:46:41 INFO - PROCESS | 22012 | |4;audio-oscillator;87;84;120;84;85;84;116;85;84;120 11:46:41 INFO - PROCESS | 22012 | |5;imaging-gaussian-blur;118;116;117;117;117;117;117;117;117;118 11:46:41 INFO - PROCESS | 22012 | |6;imaging-darkroom;147;146;146;146;146;146;147;146;146;145 11:46:41 INFO - PROCESS | 22012 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 11:46:41 INFO - PROCESS | 22012 | |8;json-parse-financial;65;64;64;65;65;64;64;80;63;61 11:46:41 INFO - PROCESS | 22012 | |9;json-stringify-tinderbox;65;66;64;65;65;65;66;66;65;65 11:46:41 INFO - PROCESS | 22012 | |10;stanford-crypto-aes;86;87;83;83;83;85;84;84;86;83 11:46:41 INFO - PROCESS | 22012 | |11;stanford-crypto-ccm;132;131;131;131;137;131;127;129;167;141 11:46:41 INFO - PROCESS | 22012 | |12;stanford-crypto-pbkdf2;173;172;167;168;166;170;173;170;167;169 11:46:41 INFO - PROCESS | 22012 | |13;stanford-crypto-sha256-iterative;68;72;71;68;66;66;69;66;69;65 11:46:41 INFO - PROCESS | 22012 | __end_tp_report 11:46:41 INFO - PROCESS | 22012 | __start_cc_report 11:46:41 INFO - PROCESS | 22012 | _x_x_mozilla_cycle_collect,1937 11:46:41 INFO - PROCESS | 22012 | __end_cc_report 11:46:41 INFO - PROCESS | 22012 | __startTimestamp1464374801649__endTimestamp 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | ------- Summary: start ------- 11:46:41 INFO - PROCESS | 22012 | Number of tests: 14 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:1.57 (1.4%) stddev-sans-first:1.64 11:46:41 INFO - PROCESS | 22012 | Values: 113.0 108.0 113.0 113.0 112.0 113.0 113.0 112.0 113.0 113.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#1] audio-beat-detection Cycles:10 Average:148.90 Median:152.00 stddev:20.38 (13.4%) stddev-sans-first:21.05 11:46:41 INFO - PROCESS | 22012 | Values: 162.0 132.0 132.0 185.0 133.0 142.0 162.0 137.0 175.0 129.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#2] audio-dft Cycles:10 Average:163.60 Median:162.00 stddev:6.67 (4.1%) stddev-sans-first:7.01 11:46:41 INFO - PROCESS | 22012 | Values: 161.0 162.0 162.0 160.0 159.0 161.0 163.0 165.0 182.0 161.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.50 stddev:11.00 (14.6%) stddev-sans-first:11.41 11:46:41 INFO - PROCESS | 22012 | Values: 74.0 77.0 75.0 105.0 75.0 76.0 76.0 75.0 97.0 75.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:16.46 (19.6%) stddev-sans-first:17.21 11:46:41 INFO - PROCESS | 22012 | Values: 87.0 84.0 120.0 84.0 85.0 84.0 116.0 85.0 84.0 120.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 11:46:41 INFO - PROCESS | 22012 | Values: 118.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 11:46:41 INFO - PROCESS | 22012 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 11:46:41 INFO - PROCESS | 22012 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:64.50 stddev:5.23 (8.1%) stddev-sans-first:5.55 11:46:41 INFO - PROCESS | 22012 | Values: 65.0 64.0 64.0 65.0 65.0 64.0 64.0 80.0 63.0 61.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 11:46:41 INFO - PROCESS | 22012 | Values: 65.0 66.0 64.0 65.0 65.0 65.0 66.0 66.0 65.0 65.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.50 stddev:1.51 (1.8%) stddev-sans-first:1.48 11:46:41 INFO - PROCESS | 22012 | Values: 86.0 87.0 83.0 83.0 83.0 85.0 84.0 84.0 86.0 83.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:131.50 stddev:11.70 (8.9%) stddev-sans-first:12.33 11:46:41 INFO - PROCESS | 22012 | Values: 132.0 131.0 131.0 131.0 137.0 131.0 127.0 129.0 167.0 141.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:170.00 stddev:2.55 (1.5%) stddev-sans-first:2.37 11:46:41 INFO - PROCESS | 22012 | Values: 173.0 172.0 167.0 168.0 166.0 170.0 173.0 170.0 167.0 169.0 11:46:41 INFO - PROCESS | 22012 | 11:46:41 INFO - PROCESS | 22012 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:2.31 (3.4%) stddev-sans-first:2.45 11:46:41 INFO - PROCESS | 22012 | Values: 68.0 72.0 71.0 68.0 66.0 66.0 69.0 66.0 69.0 65.0 11:46:41 INFO - PROCESS | 22012 | -------- Summary: end -------- 11:46:41 INFO - PROCESS | 22012 | 11:46:42 INFO - TEST-INFO | 22012: exit 0 11:46:42 INFO - TEST-OK | kraken | took 64061ms 11:46:42 INFO - SUITE-END | took 1182s 11:46:42 INFO - Completed test suite (00:19:42) 11:46:42 INFO - javascript benchmark 11:46:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.6636670416196, 2692.784577233821, 2730.0035487047226, 2732.6037724529956, 2748.82132661377, 4661.88345291367, 4665.463471700756, 4696.225122854734, 4761.952380476198, 4803.5916947059495, 4725.102230576188, 4752.596029337502, 4804.279358096287, 4817.759112044392, 4840.757962101899, 4321.503027151886, 4336.97831510842, 4353.760543170124, 4446.710963787352, 4465.285554311311, 4351.108022855323, 4421.646268298545, 4449.57729015743, 4509.79705913233, 4565.155446242439, 4479.4176757021705, 4515.412996310475, 4518.615917647004, 4525.524819893913, 4540.091981603676, 4268.167707297078, 4319.546447623017, 4331.566843315674, 4356.455443069616, 4400.889977750582, 4301.247281725717, 4306.935395969063, 4354.368616550614, 4366.432363792718, 4404.603585677272, 4681.297805329194, 4681.765911704401, 4696.835610753641, 4697.6241900647865, 4704.976475117637, 4962.702237865704, 5091.210862316309, 5158.0, 5168.870778230536, 5233.9738301308635, 5202.895942081175, 5227.634065615409, 5228.816991405321, 5248.606354523426, 5249.028929648028, 4656.674032817738, 4834.6374021948495, 4834.758262086952, 4867.586255168315, 4885.7312847793355, 5168.405633352138, 5250.448702886219, 5250.789968401221, 5265.76304066318, 5323.733813309396, 5103.336566246438, 5129.512696293846, 5148.613853960893, 5149.871253218699, 5152.845414637567, 4999.400071991385, 5185.429602743695, 5193.091209038404, 5241.947580524146, 5250.133727934848, 5149.45930677281, 5161.09680805865, 5161.66449180802, 5194.065068287745, 5207.3230480037355, 5143.691378517226, 5147.408048074444, 5154.278401023859, 5157.045946499909, 5210.478952104835, 4292.5063617683745, 4335.241332766753, 4394.472663280363, 4465.0, 4466.0398014427055, 3861.691064714816, 3861.826217820205, 3872.0900588361715, 3889.163829776555, 3930.0, 4424.80088396023, 4443.133588950188, 4448.866534003985, 4483.820647174077, 4485.0, 5074.13739664258, 5127.077126117335, 5179.8446046618665, 5203.427622961472, 5208.374995000624, 4423.73457592539, 4442.911141777211, 4486.035502367007, 4488.708233964782, 4492.191405546968, 4735.881602959954, 4736.526347365236, 4738.19450693383, 4743.549362810528, 4775.6657034007985, 4725.054989002282, 4747.359106520576, 4939.110960027229, 4940.11078005948, 4989.750512474435, 5054.418741844588, 5084.1102807009365, 5085.186370180753, 5088.414832294334, 5130.97434512825, 4790.0, 4809.0862736080035, 4818.325434439252, 4820.325154478446, 4867.7322747249245, 4971.950280497149, 4986.426560945538, 5012.348394708811, 5038.798448062036, 5060.3168572242275, 5137.998090372491, 5161.0, 5163.509466600667, 5165.302684137593, 5205.245239440335, 5005.274235235943, 5015.974920125522, 5017.84946451607, 5022.924656130234, 5067.113255180255, 4910.0, 4948.678335908155, 4993.950060499348, 5002.899942001212, 5021.924671130009, 4926.975365123151, 4945.282933974626, 4958.380202474691, 4979.800807967786, 4981.825636102719, 5082.949170508247, 5083.847484575469, 5084.898302033865, 5107.591392688651, 5139.768710408115, 4799.616030717604, 4817.060673168839, 4871.585915197248, 4877.0, 4920.901581968412], "value": 4726.801873328616, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.142937155829, 587.1075964533909, 597.6921885229106, 597.7907732293696, 603.7434090511531, 39454.0, 40763.59236407635, 40911.38632920501, 40997.59002409975, 43771.3434298485, 40133.0, 40565.79717101418, 40634.0, 40745.18509629805, 40777.38833917493, 7570.810729731773, 7715.537067775937, 7922.683092676286, 7929.563873986957, 10070.899291007107, 9943.701688949306, 10020.649277275297, 10022.24833137514, 10023.348482348623, 10031.047050530187, 10360.481975901175, 10447.582096716122, 10451.163906887468, 10453.947730261338, 10456.96089262594, 7618.619069046581, 7869.331106855924, 7884.093329267151, 10213.080822725917, 10269.0, 9945.950270248675, 10066.446345450997, 10192.745181370525, 10196.133328666998, 10203.642872499428, 38716.41925371122, 38756.80621596902, 39360.606393936265, 39422.80288598567, 39491.40762888559, 3117.0, 3126.7342275906576, 3625.29306785177, 3632.945505817415, 3747.981260093682, 28272.85863570669, 30600.54099188514, 30725.385492290254, 31026.689733102605, 32169.0, 3467.09855437588, 3487.137769223908, 3497.790132592027, 3604.9639503605154, 3676.8345424455697, 264667.0, 267006.9999999981, 267169.0, 267317.0, 267457.0, 57818.4218157813, 59038.70480647569, 59063.704681476316, 59160.11259831106, 59261.40738592645, 34018.0, 35182.472262915566, 35202.0, 37247.441288380694, 37281.81359093187, 15391.923040384727, 15516.301766420538, 15519.146446945526, 15538.766918496232, 15554.377824886878, 10376.844347334796, 11453.770924581462, 11690.766184676258, 11713.765724685629, 11956.222845515187, 19482.64783091661, 19594.0, 19640.508987275432, 19737.605247895248, 19778.10998505071, 7433.516821406592, 7502.286368247752, 7614.543127412373, 7764.184760600168, 7794.298513133732, 770.7418014964937, 870.4472659860908, 924.6578765856542, 944.6457578408097, 991.4794732765356, 27859.303517412227, 28082.85958570194, 28369.0, 28848.278793030342, 29047.56428653572, 953.7663272498282, 998.0817647764075, 1009.9242556808268, 1017.8829434614966, 1029.0378496106016, 14605.78091328631, 15069.623259418602, 15080.321385537678, 15123.470678526199, 15417.691646167013, 404474.0, 409327.0, 411660.65018049505, 412722.0, 412954.0, 50025.24962125571, 50437.0, 50534.24198637023, 50625.0, 50648.454608177075, 1108.894655007773, 1146.633077415219, 1210.9757804843853, 1218.1107791312215, 1235.5922545559943, 27026.32434189161, 28846.278843029093, 29733.0, 29736.405271894873, 29759.404811904074, 42020.36969445522, 42244.577554224066, 42357.0, 42502.36246456244, 42535.0, 303859.0, 306223.0, 306894.0, 307124.0, 307508.0, 22407.103715851834, 24871.50256994886, 25444.236672899842, 25664.23007309784, 25962.610560841225, 41803.0, 42207.788961055, 42517.832412549906, 42781.0, 43187.56812431961, 35486.82256588804, 35986.28027439489, 36760.44859327059, 36769.08077298089, 37164.0, 35677.0, 35761.431411530815, 36261.81869090637, 36304.81847590745, 36565.08587285339, 32542.51186232161, 32633.836830815693, 35291.29417411586, 35834.10414739652, 36452.0, 31219.84390078035, 32493.675063249066, 32578.51132232971, 32720.0, 32840.178995525304, 24954.500909981336, 25212.24363269105, 25417.61873571935, 26332.8683356582, 27371.72628273692], "value": 18212.15414656166, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.51824555096175, 360.7186394612202, 363.8308186693189, 380.1750202061526, 380.4388526922791, 15821.0, 16524.917375413104, 16569.25438355274, 16929.49211523653, 16947.57631059224, 14129.576112716635, 14271.0, 14293.999420040602, 14317.284135793194, 14317.928410357934, 4889.168841296988, 4955.925661115086, 5128.871778205537, 5143.691378517282, 5144.742762861861, 3638.5633723953165, 3824.9043773905737, 4986.75066246688, 5525.502704756571, 5840.474357307841, 5875.500582450497, 5884.411558844103, 5886.970565147169, 5901.881962360751, 5904.0, 5754.482096611283, 5816.0, 5897.439743224409, 5901.734421951022, 5935.910961335583, 3333.4999750037446, 4723.622110231173, 5070.543651071422, 5079.593632509391, 5174.094533456668, 19105.904470477697, 19735.80264197354, 19784.0, 20074.0, 20108.39674809758, 1902.7050807124917, 1929.5754933914516, 1953.8632295739303, 2090.6654935210295, 2096.371088673407, 12656.55702050433, 12717.682057948532, 12750.681232969157, 13063.934680326538, 13076.0, 1736.782902137233, 1763.8765286429827, 1803.2156012134762, 1808.8010318864915, 1837.0814592703648, 62206.68896655533, 62220.37779622191, 62714.0, 63528.68235658838, 63658.68170659117, 8670.91329086701, 8830.293576513863, 9022.323325750594, 9062.410943288665, 9133.49765762876, 12162.391880406121, 12465.563705270453, 12478.937605311916, 12483.750324993449, 12493.875061249453, 6890.276520965327, 6898.724051037902, 6962.477814163958, 7025.40284075849, 7067.752628657972, 6678.0, 6704.094947182166, 6766.49251306154, 6868.793936181923, 6898.379145876896, 5782.681952492653, 5817.272840894888, 5817.650940943557, 5867.911981320283, 5876.44173803488, 1521.8630323270959, 1622.5375767906326, 1643.958901027484, 1690.1633691322875, 1769.4426255729404, 894.6287290774255, 905.1672461335587, 908.9318301127441, 992.3004281981185, 1023.902729240721, 15859.0, 16776.0, 17173.398931037354, 17741.556461088578, 18099.819001809814, 870.5125129927135, 876.2770714160843, 883.1080608585247, 927.1933417926447, 959.856021596766, 9642.855357169648, 10185.134263587677, 10219.080282774587, 10224.028717271847, 10256.0, 382830.0, 383817.08091459924, 383932.0, 384842.0, 391520.0, 73976.0, 75099.0, 75988.24011759923, 76644.61677691687, 77906.0, 952.2429668413628, 956.1442508954447, 964.62379671928, 968.7190714692802, 985.5564995752084, 15185.544433667008, 15239.3904243829, 15263.694726105194, 15822.841771582136, 15833.366665333719, 31764.682353176173, 32423.865164719122, 33594.32811343808, 34424.13939651529, 34686.65313346935, 47946.76026619845, 48248.0, 49596.75201623969, 49609.0, 49959.25061124159, 7130.465215108784, 7157.892631610426, 7186.425085993003, 7315.231900650355, 7366.594837284001, 11240.887591123985, 11357.772844543228, 11361.204715669825, 11437.542498299976, 11534.884651153381, 9579.90420095818, 9649.469279189712, 9651.855222171533, 9915.25635577349, 9971.900280997388, 9735.464549449849, 9873.851892221479, 9971.102600765966, 10022.448765317977, 10132.442715650415, 9638.614455421704, 9663.516824158905, 9887.110160085627, 9961.950190249003, 10002.799944001225, 6957.930420695729, 7018.894716579358, 7030.753923612649, 7102.715891364288, 7123.679434425567, 9698.224142068477, 9845.113939745457, 9877.160441362565, 9949.0, 9962.252831037846], "value": 8124.798057226496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.5646458436424, 1429.3853642933539, 1431.7566013777657, 1582.9129397883112, 1778.279796682344, 6698.0, 7522.097348318202, 7751.418643601724, 7763.223677632234, 7969.601519924009, 7665.0, 7864.567448790314, 7866.0, 7884.605769711506, 7894.092179399371, 2181.5309708412688, 2208.9668654970187, 2212.126210146991, 2212.7565967743535, 2215.3575463115676, 2173.717416735822, 2211.4139752965475, 2235.0724449353497, 2235.3740952533235, 2239.305815197286, 2182.334388011656, 2203.6804663323887, 2212.037763572851, 2212.4468882779306, 2215.556888622282, 2125.2774056820676, 2126.861753985986, 2132.7973842485017, 2133.328001679466, 2133.775953524889, 2093.6964140199584, 2128.372130221588, 2129.084493667722, 2129.9787002129933, 2130.360891732474, 7591.924080759177, 7690.0, 7764.650590723431, 7787.026621672291, 7801.804954876116, 4646.4424269087585, 4771.928421073687, 4775.068861571995, 4777.235642297251, 4777.28340748887, 2907.592936988823, 2923.824570525754, 2969.376430949482, 2999.2651800309063, 3020.592220050287, 887.9778005549848, 907.6505545365083, 917.4816228830756, 924.8658944453084, 942.1144124522995, 10448.52981615829, 10482.056614904695, 10489.160867130611, 10521.684349469528, 10550.366977981268, 3908.355121404936, 4037.5154981401834, 4085.20338533989, 4099.200655872136, 4107.219628270619, 8306.70926517581, 8372.832543349221, 8383.622736976851, 8389.07720150783, 8400.789980250544, 4433.645308375322, 4435.733855968588, 4438.134563760036, 4441.467023957146, 4449.555044495524, 3754.81225938702, 4127.711060225755, 4141.295979683461, 4148.066684995852, 4325.848595299212, 3434.5706786651667, 3476.965230347664, 3508.4035713928693, 3545.8758943436856, 3669.6697297243377, 1034.9793004139874, 1043.400044974143, 1044.6970378590277, 1051.3376572759112, 1053.3100818963592, 847.0470720439506, 855.6449073634496, 856.1010938514535, 858.051852702767, 863.447393668053, 4960.206366981266, 5023.09584274834, 5100.081985242692, 5121.0, 5162.870928226749, 898.4788822482944, 932.8693982842408, 947.052947052947, 953.8903026151943, 991.2912267728609, 3459.9481007784902, 3540.168060505779, 3599.046252743025, 3624.9637503625154, 3648.1426864686778, 9229.400088994194, 9454.290928180413, 9525.523723813782, 9599.664011759554, 9640.517974101267, 9525.714228573153, 9532.332736708364, 9573.18627916635, 9592.280578956606, 9631.133198012212, 6884.759033433906, 7271.454640901953, 7396.630168491662, 7397.37122344596, 7413.8146546336775, 5171.534561889448, 5210.088234559013, 5232.3459567554055, 5363.222332761806, 5438.18427235902, 8242.17578242171, 8368.20502052304, 8633.223009929137, 8633.69782057625, 8653.870191947251, 8357.66569337244, 8703.0, 8704.477731336141, 8710.69512567057, 8762.386632935633, 6066.636001839904, 6187.3503282154725, 6188.195534580475, 6674.365935236145, 6809.829754255985, 6591.406773390418, 6772.221194562689, 7161.248068952893, 7229.963850180716, 7422.665980030803, 6610.4050635443045, 6794.0, 6832.316768323128, 6833.4191593715095, 6944.652767361713, 6704.094947182068, 6723.663816809238, 6724.831879202863, 6808.5914845109455, 6808.7276508939085, 6007.579469437097, 6033.818985430444, 6085.543584231112, 6087.878242435215, 6098.634081955097, 4338.7830608470085, 4339.934900976425, 4404.7357158570585, 4434.955650443454, 4436.245838207577, 4570.154521413549, 4575.542445755398, 4580.312953057068, 4583.610393116489, 4586.403767510203], "value": 4082.023428198514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.62478777589126, 288.92199106241316, 292.09450702821255, 294.6228827101311, 296.1677685703175, 4805.303231031498, 4870.878228044301, 4882.6093912487, 4906.68106573073, 4906.779194936227, 4778.784954677039, 4793.400824896888, 4847.175980083385, 4853.247746599274, 4883.536064073916, 3556.199855032616, 3689.815509224528, 3694.9445758313645, 3772.2832661794173, 3808.0, 3726.7763934164036, 3759.229357981621, 3778.8110594470168, 3796.1458671798764, 3797.316483033053, 3531.876384326549, 3747.4940882980723, 3749.737518373715, 3750.324941510527, 3762.680172185354, 3590.910227244314, 3746.2132952080096, 3760.8683696070507, 3772.2078363543687, 3772.2266935278203, 3297.225152089257, 3299.950500742491, 3308.2721801203697, 3314.9171270718184, 3318.452455344865, 4584.564466375689, 4601.516840731709, 4609.124266389409, 4635.447125213058, 4636.884077898046, 4038.3846461415374, 4235.470566179228, 4235.597618226264, 4296.140771845643, 4309.418228539138, 5038.823641172578, 5046.5205805448795, 5054.494550544916, 5062.873428164288, 5070.366204224472, 3536.6993805526563, 3541.6281290464644, 3546.5744110706623, 3548.6628770266784, 3550.254446566224, 5114.488551144893, 5295.443978382253, 5312.1766126250495, 5339.252504649352, 5340.0, 3551.5438670145118, 3560.537130173113, 3580.803055831902, 3582.5342705448124, 3587.318409502186, 3595.352836489457, 3617.8603739821874, 3618.0, 3626.854925802991, 3636.654517820803, 4175.91648167035, 4178.310578754532, 4208.421841809321, 4225.556316586775, 4233.364995250737, 4942.184539551005, 5032.314174751426, 5053.974730126326, 5068.352785344704, 5122.667026643257, 1243.8742937641448, 1252.7557126360455, 1256.4220458589152, 1262.3561983388354, 1266.1390254626942, 780.3718007004416, 801.4790386248985, 838.9680692747833, 844.5650489997656, 845.8392905347964, 4427.535108813592, 4436.866893993185, 4444.711033800162, 4450.46594408673, 4468.642508599304, 5082.33929589151, 5121.692698438105, 5126.436092029874, 5130.615203859726, 5141.845744627667, 4248.660107191417, 4393.7152680605905, 4396.956030439655, 4405.383246345515, 4420.668449866273, 2723.338763354352, 2734.371094648242, 2741.4105967217142, 2758.1311886755607, 2758.3517873299757, 4894.388201474816, 4932.161532539477, 4946.282788995575, 4946.505349465025, 4958.9504104959215, 5043.814703544664, 5253.44838791929, 5262.842114736564, 5280.9207861882105, 5291.973540132275, 4905.479301416501, 4905.533974272438, 4928.433230178575, 4932.90134197314, 4937.21004639256, 5129.076766182123, 5155.355580552431, 5167.974160129175, 5174.0, 5175.430702622633, 4850.951490485191, 5066.834628035499, 5092.363454568179, 5169.689818610745, 5184.274201611702, 4964.280179374043, 4966.379202599675, 4967.875803104951, 5014.122528557561, 5016.297718319511, 3531.205478767257, 3571.5714114306447, 3580.1407662160373, 3603.747737658339, 3612.9096772580897, 5154.0, 5244.239585260039, 5251.947480525146, 5258.290130832289, 5275.762590683506, 5030.245463180552, 5046.646734728533, 5099.643024988215, 5110.540051395392, 5122.923156152735, 5099.515546023122, 5101.7704203310195, 5115.974420127875, 5131.640785145004, 5137.306463627512, 4745.406824146981, 4863.4650188479945, 4906.754662266943, 5011.899762004812, 5046.394432668103, 4936.222959734421, 4950.430700469492, 4952.504749525018, 4960.47914968923, 4967.826126085714, 4820.542048505386, 4997.9750101249265, 5001.449550639313, 5012.498750124958, 5015.1975683890405], "value": 3779.73062779357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.3027941778804, 703.9190493093296, 735.9190489046207, 736.4035131543446, 745.8471013442245, 11697.3566453845, 12600.369981500911, 12618.747625047494, 12622.24266544008, 12622.558210462636, 12135.0, 12158.27050376978, 12170.148089633729, 12171.756564868698, 12183.269003859774, 617.2315467243288, 751.868423025971, 787.0555333599675, 787.8778789287669, 791.8733002719566, 786.268770043859, 789.7433334166419, 792.2315354106507, 792.8216151365925, 793.6349279331514, 655.931127231641, 837.401258100458, 838.2078935406043, 838.9496630202208, 839.4543546694666, 798.9800254993613, 825.4923222218292, 829.8423299573062, 831.3058596072257, 831.4553967151526, 608.1971797227661, 608.4189598933008, 608.89344364736, 609.2049874913237, 609.3906093906094, 10625.574977000912, 10668.14654827612, 10680.679579612624, 10685.252032357737, 10745.946270268676, 11154.498047587878, 11163.49764260602, 11343.716407089807, 11470.197086203969, 11779.646610601696, 10308.381497110122, 10320.638777642747, 10420.791584168273, 10595.84106238407, 10657.573697052108, 3828.559715632682, 3861.9613803861885, 3883.825227864753, 3924.3917192835156, 3986.720929534934, 43321.350179747016, 43888.780556097336, 45264.99999999967, 45286.54713452856, 45317.54682453166, 9147.725568232963, 9150.801989910991, 9360.391574547633, 9441.858372124423, 9565.61737530491, 16682.749758753627, 16687.165641717864, 16756.07841568701, 16937.32250709958, 16975.830241697673, 6352.205974253218, 6407.647579383179, 6418.069379939882, 6503.967480162569, 6557.278699343068, 3152.3064925716308, 3328.9500657490157, 3411.5053317269353, 3428.2629234714655, 3459.619441861393, 2626.7898568114506, 2687.9596806047925, 2690.421559364746, 2703.162019773876, 2706.323419145214, 420.93096057986844, 507.2467385931888, 536.2171230004237, 536.2947723743265, 551.8427248234315, 565.7143142712986, 568.1335962656981, 591.249113625693, 603.4026313949158, 619.7490016543308, 7306.59813710251, 7540.0, 7662.540247585162, 7833.532457188417, 7862.135165131831, 487.76831005272214, 580.2659635561018, 596.6023496189162, 604.5949214026613, 619.9194104766351, 3654.6528079832374, 3676.7242456815848, 3700.9814950925074, 3752.9249415011545, 3781.262653782541, 55530.72234638801, 57483.0, 57575.71212143912, 57656.71171644199, 58307.41692583105, 42400.36399454011, 42964.925876853325, 44383.0, 47442.76278618585, 51975.0, 45108.3233751494, 45377.31934020992, 45542.240564865526, 46123.53876461193, 46168.0, 8542.82914341722, 8546.401751877309, 8629.39594228398, 8657.61040753155, 8710.474136431718, 30801.845990769903, 31161.688383115878, 31670.366592668477, 31789.20526986844, 32425.0, 37228.06929826667, 38131.80934095312, 39791.80104099461, 39814.60185398109, 40089.59910400859, 10739.409332486475, 10765.730856728644, 10865.293755905841, 10917.181211409335, 10925.0, 8111.594420279081, 8702.608382622671, 9436.292278079034, 9795.265355098254, 12173.817392738938, 9069.727908162766, 9070.909290907006, 9104.544772761468, 9289.117533834275, 9300.488473133772, 8691.0, 8793.692220772242, 8813.515256660688, 8814.779630509289, 9211.355205135576, 8272.131426200162, 8435.156484351515, 8701.695440659547, 8945.73162805117, 9082.818343633222, 7890.329322007694, 7934.880976785237, 8190.401141130182, 8226.712065077694, 8244.093149753402, 7753.0, 8171.305439037511, 8306.127856574973, 8306.499701848683, 8403.327733781405], "value": 5699.678746409126, "unit": "score"}], "name": "dromaeo_css", "value": 6282.9459171477665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 113.0, 112.0, 113.0, 113.0, 112.0, 113.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 132.0, 132.0, 185.0, 133.0, 142.0, 162.0, 137.0, 175.0, 129.0], "value": 148.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 160.0, 159.0, 161.0, 163.0, 165.0, 182.0, 161.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 105.0, 75.0, 76.0, 76.0, 75.0, 97.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 120.0, 84.0, 85.0, 84.0, 116.0, 85.0, 84.0, 120.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 80.0, 63.0, 61.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 83.0, 83.0, 83.0, 85.0, 84.0, 84.0, 86.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 131.0, 131.0, 131.0, 137.0, 131.0, 127.0, 129.0, 167.0, 141.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 167.0, 168.0, 166.0, 170.0, 173.0, 170.0, 167.0, 169.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 71.0, 68.0, 66.0, 66.0, 69.0, 66.0, 69.0, 65.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1546.7, "alertThreshold": 2.0}]} 11:46:42 INFO - Return code: 0 11:46:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:46:42 ERROR - # TBPL SUCCESS # 11:46:42 INFO - Running post-action listener: _resource_record_post_action 11:46:42 INFO - ##### 11:46:42 INFO - ##### Finished run-tests step (success) 11:46:42 INFO - ##### 11:46:42 INFO - Running post-run listener: _resource_record_post_run 11:46:43 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 528031744; Read time: 0; Write time: 514408 11:46:43 INFO - TinderboxPrint: CPU usage
13.1% 11:46:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:46:43 INFO - TinderboxPrint: I/O write bytes / time
528,031,744 / 514,408 11:46:43 INFO - TinderboxPrint: CPU idle
8,324.2 (86.9%) 11:46:43 INFO - TinderboxPrint: CPU user
1,209.1 (12.6%) 11:46:43 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 248434688; Read time: 0; Write time: 272964 11:46:43 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 279597056; Read time: 0; Write time: 241444 11:46:43 INFO - Running post-run listener: _upload_blobber_files 11:46:43 INFO - Blob upload gear active. 11:46:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:43 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 11:46:43 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:46:43 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:46:43 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:46:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:44 INFO - (blobuploader) - INFO - Done attempting. 11:46:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:46:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:46:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:45 INFO - (blobuploader) - INFO - Done attempting. 11:46:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:51 INFO - (blobuploader) - INFO - Done attempting. 11:46:51 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:51 INFO - Return code: 0 11:46:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/49637eea07077ca4d5deab6fa5885075f2ffbf2f1b6f6ba54b5e05293d19d248f06a21aa6152e7b2fdcb115059853811e67719536aa2fc23123e0808a51865c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/dcc65e1166b8c09aeb55b5f087014a102ceb5d8afb535ddba7edf3355bb6e5527e571fabbeee89885a5db664f2201a94fe47ddf4a4c921d8efcae2c01f928bea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/78badaa59a3f67a04585367f4503b1b59d206745801449341b02e2673aeff1200ea451a87a782416b9d9774289c60dd6713cf2537b56418f2de91117ae7f8a49"} 11:46:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:51 INFO - Contents: 11:46:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/49637eea07077ca4d5deab6fa5885075f2ffbf2f1b6f6ba54b5e05293d19d248f06a21aa6152e7b2fdcb115059853811e67719536aa2fc23123e0808a51865c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/dcc65e1166b8c09aeb55b5f087014a102ceb5d8afb535ddba7edf3355bb6e5527e571fabbeee89885a5db664f2201a94fe47ddf4a4c921d8efcae2c01f928bea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/78badaa59a3f67a04585367f4503b1b59d206745801449341b02e2673aeff1200ea451a87a782416b9d9774289c60dd6713cf2537b56418f2de91117ae7f8a49"} 11:46:51 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:51 INFO - Copying logs to upload dir... 11:46:51 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.654943 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-27 11:46:51.227236) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:46:51.231158) ========= 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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/49637eea07077ca4d5deab6fa5885075f2ffbf2f1b6f6ba54b5e05293d19d248f06a21aa6152e7b2fdcb115059853811e67719536aa2fc23123e0808a51865c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/dcc65e1166b8c09aeb55b5f087014a102ceb5d8afb535ddba7edf3355bb6e5527e571fabbeee89885a5db664f2201a94fe47ddf4a4c921d8efcae2c01f928bea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/78badaa59a3f67a04585367f4503b1b59d206745801449341b02e2673aeff1200ea451a87a782416b9d9774289c60dd6713cf2537b56418f2de91117ae7f8a49"} build_url:https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011527 build_url: 'https://queue.taskcluster.net/v1/task/IPRfYM0ARuWM9X6uYkm2Rw/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/Larch-Non-PGO/sha512/49637eea07077ca4d5deab6fa5885075f2ffbf2f1b6f6ba54b5e05293d19d248f06a21aa6152e7b2fdcb115059853811e67719536aa2fc23123e0808a51865c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/dcc65e1166b8c09aeb55b5f087014a102ceb5d8afb535ddba7edf3355bb6e5527e571fabbeee89885a5db664f2201a94fe47ddf4a4c921d8efcae2c01f928bea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/78badaa59a3f67a04585367f4503b1b59d206745801449341b02e2673aeff1200ea451a87a782416b9d9774289c60dd6713cf2537b56418f2de91117ae7f8a49"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:46:51.257187) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:46:51.257643) ========= 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=0e2a256762fed810e2f0f810000001b3-1464373566.198236-216345834 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:46:51.342345) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:46:51.343089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:46:51.343805) ========= ========= Total master_lag: 0.22 =========