builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1464930289.54 results: success (0) buildid: 20160602205209 builduid: ba23e03f45f7424dac4f9df69ecbd33b revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.540890) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.541403) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.541739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.572356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.572665) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 22:04:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.7M=0.001s 2016-06-02 22:04:50 (20.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108080 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.694746) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.695074) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020408 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.744907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:49.745332) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a39da695528a04705d25d0159d6250917c95eee0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a39da695528a04705d25d0159d6250917c95eee0 --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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 22:04:50,210 truncating revision to first 12 chars 2016-06-02 22:04:50,210 Setting DEBUG logging. 2016-06-02 22:04:50,210 attempt 1/10 2016-06-02 22:04:50,210 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a39da695528a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 22:04:50,546 unpacking tar archive at: mozilla-central-a39da695528a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.958788 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:50.721155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:50.721522) ========= script_repo_revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:50.721997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:50.722329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:04:50.733600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-02 22:04:50.733931) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:04:51 INFO - MultiFileLogger online at 20160602 22:04:51 in /builds/slave/test 22:04:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:04:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:04:51 INFO - {'append_to_log': False, 22:04:51 INFO - 'base_work_dir': '/builds/slave/test', 22:04:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:04:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:04:51 INFO - 'branch': 'Firefox-Non-PGO', 22:04:51 INFO - 'buildbot_json_path': 'buildprops.json', 22:04:51 INFO - 'config_files': ('talos/linux_config.py',), 22:04:51 INFO - 'default_actions': ('clobber', 22:04:51 INFO - 'read-buildbot-config', 22:04:51 INFO - 'download-and-extract', 22:04:51 INFO - 'populate-webroot', 22:04:51 INFO - 'create-virtualenv', 22:04:51 INFO - 'install', 22:04:51 INFO - 'run-tests'), 22:04:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:04:51 INFO - 'download_minidump_stackwalk': True, 22:04:51 INFO - 'download_symbols': 'ondemand', 22:04:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:04:51 INFO - 'tooltool.py': '/tools/tooltool.py', 22:04:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:04:51 INFO - '/tools/misc-python/virtualenv.py')}, 22:04:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:04:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:04:51 INFO - 'installer_path': 'installer.exe', 22:04:51 INFO - 'log_level': 'info', 22:04:51 INFO - 'log_name': 'talos', 22:04:51 INFO - 'log_to_console': True, 22:04:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:04:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:04:51 INFO - 'opt_config_files': (), 22:04:51 INFO - 'pip_index': False, 22:04:51 INFO - 'sps_profile': False, 22:04:51 INFO - 'sps_profile_interval': 0, 22:04:51 INFO - 'suite': 'dromaeojs', 22:04:51 INFO - 'system_bits': '32', 22:04:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:04:51 INFO - 'title': 'talos-linux64-ix-012', 22:04:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:04:51 INFO - 'use_talos_json': True, 22:04:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:04:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:04:51 INFO - 'work_dir': 'build'} 22:04:51 INFO - ##### 22:04:51 INFO - ##### Running clobber step. 22:04:51 INFO - ##### 22:04:51 INFO - Running pre-action listener: _resource_record_pre_action 22:04:51 INFO - Running main action method: clobber 22:04:51 INFO - rmtree: /builds/slave/test/build 22:04:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:04:51 INFO - Running post-action listener: _resource_record_post_action 22:04:51 INFO - ##### 22:04:51 INFO - ##### Finished clobber step (success) 22:04:51 INFO - ##### 22:04:51 INFO - ##### 22:04:51 INFO - ##### Running read-buildbot-config step. 22:04:51 INFO - ##### 22:04:51 INFO - Running pre-action listener: _resource_record_pre_action 22:04:51 INFO - Running main action method: read_buildbot_config 22:04:51 INFO - Using buildbot properties: 22:04:51 INFO - { 22:04:51 INFO - "project": "", 22:04:51 INFO - "product": "firefox", 22:04:51 INFO - "script_repo_revision": "production", 22:04:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:04:51 INFO - "repository": "", 22:04:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 22:04:51 INFO - "buildid": "20160602205209", 22:04:51 INFO - "pgo_build": "False", 22:04:51 INFO - "basedir": "/builds/slave/test", 22:04:51 INFO - "buildnumber": 606, 22:04:51 INFO - "slavename": "talos-linux64-ix-012", 22:04:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:04:51 INFO - "platform": "ubuntu64_hw", 22:04:51 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 22:04:51 INFO - "branch": "mozilla-central", 22:04:51 INFO - "repo_path": "mozilla-central", 22:04:51 INFO - "revision": "a39da695528a04705d25d0159d6250917c95eee0", 22:04:51 INFO - "stage_platform": "linux64", 22:04:51 INFO - "builduid": "ba23e03f45f7424dac4f9df69ecbd33b", 22:04:51 INFO - "slavebuilddir": "test" 22:04:51 INFO - } 22:04:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:04:51 INFO - Running post-action listener: _resource_record_post_action 22:04:51 INFO - ##### 22:04:51 INFO - ##### Finished read-buildbot-config step (success) 22:04:51 INFO - ##### 22:04:51 INFO - ##### 22:04:51 INFO - ##### Running download-and-extract step. 22:04:51 INFO - ##### 22:04:51 INFO - Running pre-action listener: _resource_record_pre_action 22:04:51 INFO - Running main action method: download_and_extract 22:04:51 INFO - mkdir: /builds/slave/test/build/tests 22:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:51 INFO - https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:04:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:04:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:04:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:04:56 INFO - Downloaded 1361 bytes. 22:04:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:04:56 INFO - Using the following test package requirements: 22:04:56 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:04:56 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:04:56 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:04:56 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'jsshell-linux-x86_64.zip'], 22:04:56 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:04:56 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:04:56 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:04:56 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:04:56 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:04:56 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:04:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:04:56 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')]} 22:04:56 INFO - https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:04:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:04:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:04:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:04:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:04:59 INFO - Downloaded 22850457 bytes. 22:04:59 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'] 22:04:59 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 22:05:00 INFO - Return code: 0 22:05:00 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 22:05:00 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')]} 22:05:00 INFO - https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:14 INFO - Downloaded 22850457 bytes. 22:05:14 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'] 22:05:14 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 22:05:15 INFO - Return code: 0 22:05:15 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')]} 22:05:15 INFO - https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:05:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:18 INFO - Downloaded 10834055 bytes. 22:05:18 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'] 22:05:18 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 22:05:18 INFO - Return code: 0 22:05:18 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')]} 22:05:18 INFO - https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:05:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FTfy4IxPTEOrF16sOddK-g/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 22:05:20 INFO - Downloaded 55084712 bytes. 22:05:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:20 INFO - mkdir: /builds/slave/test/properties 22:05:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:20 INFO - Contents: 22:05:20 INFO - build_url:https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:21 INFO - Running post-action listener: _resource_record_post_action 22:05:21 INFO - Running post-action listener: set_extra_try_arguments 22:05:21 INFO - ##### 22:05:21 INFO - ##### Finished download-and-extract step (success) 22:05:21 INFO - ##### 22:05:21 INFO - ##### 22:05:21 INFO - ##### Running populate-webroot step. 22:05:21 INFO - ##### 22:05:21 INFO - Running pre-action listener: _resource_record_pre_action 22:05:21 INFO - Running main action method: populate_webroot 22:05:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:21 INFO - '--tppagecycles', 22:05:21 INFO - '7'], 22:05:21 INFO - 'tests': ['tsvgm']}}, 22:05:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['damp', 'tps']}, 22:05:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['damp', 'tps']}, 22:05:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['dromaeo_dom']}, 22:05:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['a11yr', 22:05:21 INFO - 'ts_paint', 22:05:21 INFO - 'tpaint', 22:05:21 INFO - 'sessionrestore', 22:05:21 INFO - 'sessionrestore_no_auto_restore', 22:05:21 INFO - 'tabpaint']}, 22:05:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tsvgx', 22:05:21 INFO - 'tsvgr_opacity', 22:05:21 INFO - 'tart', 22:05:21 INFO - 'tscrollx', 22:05:21 INFO - 'cart']}, 22:05:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:21 INFO - 'tsvgr_opacity', 22:05:21 INFO - 'tart', 22:05:21 INFO - 'tscrollx', 22:05:21 INFO - 'cart']}, 22:05:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s'], 22:05:21 INFO - 'tests': ['tp5o']}, 22:05:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'tests': ['tp5o']}, 22:05:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--disable-e10s', 22:05:21 INFO - '--xperf_path', 22:05:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:21 INFO - 'tests': ['tp5n']}, 22:05:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:21 INFO - 'talos_options': ['--xperf_path', 22:05:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:21 INFO - 'tests': ['tp5n']}}, 22:05:21 INFO - 'talos.zip': {'path': '', 22:05:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:21 INFO - Running post-action listener: _resource_record_post_action 22:05:21 INFO - ##### 22:05:21 INFO - ##### Finished populate-webroot step (success) 22:05:21 INFO - ##### 22:05:21 INFO - ##### 22:05:21 INFO - ##### Running create-virtualenv step. 22:05:21 INFO - ##### 22:05:21 INFO - Running pre-action listener: _resource_record_pre_action 22:05:21 INFO - Running main action method: create_virtualenv 22:05:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:21 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 22:05:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:21 INFO - Using real prefix '/usr' 22:05:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:22 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:24 INFO - Installing pip.................done. 22:05:24 INFO - Return code: 0 22:05:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:24 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 22:05:24 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 22:05:24 INFO - Using env: {'DISPLAY': ':0', 22:05:24 INFO - 'HOME': '/home/cltbld', 22:05:24 INFO - 'LANG': 'en_US.UTF-8', 22:05:24 INFO - 'LANGUAGE': 'en_US:en', 22:05:24 INFO - 'LOGNAME': 'cltbld', 22:05:24 INFO - 'MAIL': '/var/mail/cltbld', 22:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:24 INFO - 'MOZ_NO_REMOTE': '1', 22:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:24 INFO - 'NO_EM_RESTART': '1', 22:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:24 INFO - 'PWD': '/builds/slave/test', 22:05:24 INFO - 'SHELL': '/bin/bash', 22:05:24 INFO - 'SHLVL': '1', 22:05:24 INFO - 'TERM': 'linux', 22:05:24 INFO - 'TMOUT': '86400', 22:05:24 INFO - 'USER': 'cltbld', 22:05:24 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:24 INFO - '_': '/tools/buildbot/bin/python'} 22:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:24 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 22:05:24 INFO - Cleaning up... 22:05:24 INFO - Return code: 0 22:05:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:24 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:24 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 22:05:24 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 22:05:24 INFO - Using env: {'DISPLAY': ':0', 22:05:24 INFO - 'HOME': '/home/cltbld', 22:05:24 INFO - 'LANG': 'en_US.UTF-8', 22:05:24 INFO - 'LANGUAGE': 'en_US:en', 22:05:24 INFO - 'LOGNAME': 'cltbld', 22:05:24 INFO - 'MAIL': '/var/mail/cltbld', 22:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:24 INFO - 'MOZ_NO_REMOTE': '1', 22:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:24 INFO - 'NO_EM_RESTART': '1', 22:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:24 INFO - 'PWD': '/builds/slave/test', 22:05:24 INFO - 'SHELL': '/bin/bash', 22:05:24 INFO - 'SHLVL': '1', 22:05:24 INFO - 'TERM': 'linux', 22:05:24 INFO - 'TMOUT': '86400', 22:05:24 INFO - 'USER': 'cltbld', 22:05:24 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:24 INFO - '_': '/tools/buildbot/bin/python'} 22:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:24 INFO - Downloading/unpacking psutil>=3.1.1 22:05:24 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 22:05:24 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 22:05:24 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 22:05:24 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 22:05:24 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 22:05:24 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 22:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:26 INFO - Installing collected packages: psutil 22:05:26 INFO - Running setup.py install for psutil 22:05:26 INFO - building 'psutil._psutil_linux' extension 22:05:26 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 22:05:26 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 22:05:26 INFO - building 'psutil._psutil_posix' extension 22:05:26 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 22:05:26 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 22:05:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:27 INFO - Successfully installed psutil 22:05:27 INFO - Cleaning up... 22:05:27 INFO - Return code: 0 22:05:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:05:27 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')]} 22:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:27 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')]} 22:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:27 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:27 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 22:05:27 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 22:05:27 INFO - Using env: {'DISPLAY': ':0', 22:05:27 INFO - 'HOME': '/home/cltbld', 22:05:27 INFO - 'LANG': 'en_US.UTF-8', 22:05:27 INFO - 'LANGUAGE': 'en_US:en', 22:05:27 INFO - 'LOGNAME': 'cltbld', 22:05:27 INFO - 'MAIL': '/var/mail/cltbld', 22:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:27 INFO - 'MOZ_NO_REMOTE': '1', 22:05:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:27 INFO - 'NO_EM_RESTART': '1', 22:05:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:27 INFO - 'PWD': '/builds/slave/test', 22:05:27 INFO - 'SHELL': '/bin/bash', 22:05:27 INFO - 'SHLVL': '1', 22:05:27 INFO - 'TERM': 'linux', 22:05:27 INFO - 'TMOUT': '86400', 22:05:27 INFO - 'USER': 'cltbld', 22:05:27 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:27 INFO - '_': '/tools/buildbot/bin/python'} 22:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:05:27 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 22:05:27 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 22:05:27 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 22:05:27 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 22:05:27 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 22:05:27 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 22:05:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:05:29 INFO - Installing collected packages: mozsystemmonitor 22:05:29 INFO - Running setup.py install for mozsystemmonitor 22:05:29 INFO - Successfully installed mozsystemmonitor 22:05:29 INFO - Cleaning up... 22:05:29 INFO - Return code: 0 22:05:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:29 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:29 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 22:05:29 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 22:05:29 INFO - Using env: {'DISPLAY': ':0', 22:05:29 INFO - 'HOME': '/home/cltbld', 22:05:29 INFO - 'LANG': 'en_US.UTF-8', 22:05:29 INFO - 'LANGUAGE': 'en_US:en', 22:05:29 INFO - 'LOGNAME': 'cltbld', 22:05:29 INFO - 'MAIL': '/var/mail/cltbld', 22:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:29 INFO - 'MOZ_NO_REMOTE': '1', 22:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:29 INFO - 'NO_EM_RESTART': '1', 22:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:29 INFO - 'PWD': '/builds/slave/test', 22:05:29 INFO - 'SHELL': '/bin/bash', 22:05:29 INFO - 'SHLVL': '1', 22:05:29 INFO - 'TERM': 'linux', 22:05:29 INFO - 'TMOUT': '86400', 22:05:29 INFO - 'USER': 'cltbld', 22:05:29 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:29 INFO - '_': '/tools/buildbot/bin/python'} 22:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:29 INFO - Downloading/unpacking blobuploader==1.2.4 22:05:29 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 22:05:29 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 22:05:29 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 22:05:29 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 22:05:29 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 22:05:29 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 22:05:31 INFO - Downloading blobuploader-1.2.4.tar.gz 22:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:05:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:05:31 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 22:05:31 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 22:05:31 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 22:05:31 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 22:05:31 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 22:05:31 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 22:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:05:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:05:32 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 22:05:32 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 22:05:32 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 22:05:32 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 22:05:32 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 22:05:32 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 22:05:32 INFO - Downloading docopt-0.6.1.tar.gz 22:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:05:32 INFO - Installing collected packages: blobuploader, requests, docopt 22:05:32 INFO - Running setup.py install for blobuploader 22:05:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:05:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:05:32 INFO - Running setup.py install for requests 22:05:32 INFO - Running setup.py install for docopt 22:05:32 INFO - Successfully installed blobuploader requests docopt 22:05:32 INFO - Cleaning up... 22:05:32 INFO - Return code: 0 22:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:32 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:32 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 22:05:32 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 22:05:32 INFO - Using env: {'DISPLAY': ':0', 22:05:32 INFO - 'HOME': '/home/cltbld', 22:05:32 INFO - 'LANG': 'en_US.UTF-8', 22:05:32 INFO - 'LANGUAGE': 'en_US:en', 22:05:32 INFO - 'LOGNAME': 'cltbld', 22:05:32 INFO - 'MAIL': '/var/mail/cltbld', 22:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:32 INFO - 'MOZ_NO_REMOTE': '1', 22:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:32 INFO - 'NO_EM_RESTART': '1', 22:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:32 INFO - 'PWD': '/builds/slave/test', 22:05:32 INFO - 'SHELL': '/bin/bash', 22:05:32 INFO - 'SHLVL': '1', 22:05:32 INFO - 'TERM': 'linux', 22:05:32 INFO - 'TMOUT': '86400', 22:05:32 INFO - 'USER': 'cltbld', 22:05:32 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:32 INFO - '_': '/tools/buildbot/bin/python'} 22:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:33 INFO - Running setup.py (path:/tmp/pip-7lLh84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:33 INFO - Running setup.py (path:/tmp/pip-lm1Rjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:33 INFO - Running setup.py (path:/tmp/pip-R5Wf_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:33 INFO - Running setup.py (path:/tmp/pip-p6yyY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:33 INFO - Running setup.py (path:/tmp/pip-2FanE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:33 INFO - Running setup.py (path:/tmp/pip-2z3cqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:33 INFO - Running setup.py (path:/tmp/pip-5cDOlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:33 INFO - Running setup.py (path:/tmp/pip-cJMmRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:33 INFO - Running setup.py (path:/tmp/pip-eYfdBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:33 INFO - Running setup.py (path:/tmp/pip-QzcsxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:33 INFO - Running setup.py (path:/tmp/pip-peKfHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:33 INFO - Running setup.py (path:/tmp/pip-Z55Kx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:33 INFO - Running setup.py (path:/tmp/pip-NYZNYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:34 INFO - Running setup.py (path:/tmp/pip-3A6wbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:34 INFO - Running setup.py (path:/tmp/pip-5dC2sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:34 INFO - Running setup.py (path:/tmp/pip-cHVy_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:34 INFO - Running setup.py (path:/tmp/pip-2pStXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:05:34 INFO - Running setup.py install for manifestparser 22:05:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:05:34 INFO - Running setup.py install for mozcrash 22:05:34 INFO - Running setup.py install for mozdebug 22:05:34 INFO - Running setup.py install for mozdevice 22:05:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:05:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:05:34 INFO - Running setup.py install for mozfile 22:05:34 INFO - Running setup.py install for mozhttpd 22:05:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:05:34 INFO - Running setup.py install for mozinfo 22:05:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for mozInstall 22:05:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for mozleak 22:05:35 INFO - Running setup.py install for mozlog 22:05:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for moznetwork 22:05:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for mozprocess 22:05:35 INFO - Running setup.py install for mozprofile 22:05:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for mozrunner 22:05:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:05:35 INFO - Running setup.py install for mozscreenshot 22:05:35 INFO - Running setup.py install for moztest 22:05:35 INFO - Running setup.py install for mozversion 22:05:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:05:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:05:36 INFO - Cleaning up... 22:05:36 INFO - Return code: 0 22:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:36 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:36 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 22:05:36 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 22:05:36 INFO - Using env: {'DISPLAY': ':0', 22:05:36 INFO - 'HOME': '/home/cltbld', 22:05:36 INFO - 'LANG': 'en_US.UTF-8', 22:05:36 INFO - 'LANGUAGE': 'en_US:en', 22:05:36 INFO - 'LOGNAME': 'cltbld', 22:05:36 INFO - 'MAIL': '/var/mail/cltbld', 22:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:36 INFO - 'MOZ_NO_REMOTE': '1', 22:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:36 INFO - 'NO_EM_RESTART': '1', 22:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:36 INFO - 'PWD': '/builds/slave/test', 22:05:36 INFO - 'SHELL': '/bin/bash', 22:05:36 INFO - 'SHLVL': '1', 22:05:36 INFO - 'TERM': 'linux', 22:05:36 INFO - 'TMOUT': '86400', 22:05:36 INFO - 'USER': 'cltbld', 22:05:36 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:36 INFO - '_': '/tools/buildbot/bin/python'} 22:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:36 INFO - Running setup.py (path:/tmp/pip-ELcytU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:36 INFO - Running setup.py (path:/tmp/pip-pPORZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:36 INFO - Running setup.py (path:/tmp/pip-rwzlJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:36 INFO - Running setup.py (path:/tmp/pip-n1iJTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:36 INFO - Running setup.py (path:/tmp/pip-Hae_nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:36 INFO - Running setup.py (path:/tmp/pip-_gciOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:36 INFO - Running setup.py (path:/tmp/pip-23NaUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:36 INFO - Running setup.py (path:/tmp/pip-jlPJ7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:36 INFO - Running setup.py (path:/tmp/pip-obgaks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:36 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)) 22:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:36 INFO - Running setup.py (path:/tmp/pip-PqbJP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:37 INFO - Running setup.py (path:/tmp/pip-A4WbVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:37 INFO - Running setup.py (path:/tmp/pip-wPd7MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:37 INFO - Running setup.py (path:/tmp/pip-IFnXR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:37 INFO - Running setup.py (path:/tmp/pip-p55VvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:37 INFO - Running setup.py (path:/tmp/pip-FhexnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:37 INFO - Running setup.py (path:/tmp/pip-LIqEXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:37 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)) 22:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:37 INFO - Running setup.py (path:/tmp/pip-aVuWt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:37 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)) 22:05:37 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)) 22:05:37 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)) 22:05:37 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)) 22:05:37 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)) 22:05:37 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)) 22:05:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:05: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 22:05: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 22:05: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 22:05: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 22:05: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 22:05: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 22:05:39 INFO - Downloading blessings-1.6.tar.gz 22:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:05:39 INFO - Installing collected packages: blessings 22:05:39 INFO - Running setup.py install for blessings 22:05:39 INFO - Successfully installed blessings 22:05:39 INFO - Cleaning up... 22:05:39 INFO - Return code: 0 22:05:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:05:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:39 INFO - Reading from file tmpfile_stdout 22:05:39 INFO - Current package versions: 22:05:39 INFO - argparse == 1.2.1 22:05:39 INFO - blessings == 1.6 22:05:39 INFO - blobuploader == 1.2.4 22:05:39 INFO - docopt == 0.6.1 22:05:39 INFO - manifestparser == 1.1 22:05:39 INFO - mozInstall == 1.12 22:05:39 INFO - mozcrash == 0.17 22:05:39 INFO - mozdebug == 0.1 22:05:39 INFO - mozdevice == 0.48 22:05:39 INFO - mozfile == 1.2 22:05:39 INFO - mozhttpd == 0.7 22:05:39 INFO - mozinfo == 0.9 22:05:39 INFO - mozleak == 0.1 22:05:39 INFO - mozlog == 3.2 22:05:39 INFO - moznetwork == 0.27 22:05:39 INFO - mozprocess == 0.22 22:05:39 INFO - mozprofile == 0.28 22:05:39 INFO - mozrunner == 6.11 22:05:39 INFO - mozscreenshot == 0.1 22:05:39 INFO - mozsystemmonitor == 0.3 22:05:39 INFO - moztest == 0.7 22:05:39 INFO - mozversion == 1.4 22:05:39 INFO - psutil == 3.1.1 22:05:39 INFO - requests == 1.2.3 22:05:39 INFO - wsgiref == 0.1.2 22:05:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:39 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:39 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 22:05:39 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 22:05:39 INFO - Using env: {'DISPLAY': ':0', 22:05:39 INFO - 'HOME': '/home/cltbld', 22:05:39 INFO - 'LANG': 'en_US.UTF-8', 22:05:39 INFO - 'LANGUAGE': 'en_US:en', 22:05:39 INFO - 'LOGNAME': 'cltbld', 22:05:39 INFO - 'MAIL': '/var/mail/cltbld', 22:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:39 INFO - 'MOZ_NO_REMOTE': '1', 22:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:39 INFO - 'NO_EM_RESTART': '1', 22:05:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:39 INFO - 'PWD': '/builds/slave/test', 22:05:39 INFO - 'SHELL': '/bin/bash', 22:05:39 INFO - 'SHLVL': '1', 22:05:39 INFO - 'TERM': 'linux', 22:05:39 INFO - 'TMOUT': '86400', 22:05:39 INFO - 'USER': 'cltbld', 22:05:39 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:39 INFO - '_': '/tools/buildbot/bin/python'} 22:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 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)) 22:05:40 INFO - Cleaning up... 22:05:40 INFO - Return code: 0 22:05:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:40 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:40 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 22:05:40 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 22:05:40 INFO - Using env: {'DISPLAY': ':0', 22:05:40 INFO - 'HOME': '/home/cltbld', 22:05:40 INFO - 'LANG': 'en_US.UTF-8', 22:05:40 INFO - 'LANGUAGE': 'en_US:en', 22:05:40 INFO - 'LOGNAME': 'cltbld', 22:05:40 INFO - 'MAIL': '/var/mail/cltbld', 22:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:40 INFO - 'MOZ_NO_REMOTE': '1', 22:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:40 INFO - 'NO_EM_RESTART': '1', 22:05:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:40 INFO - 'PWD': '/builds/slave/test', 22:05:40 INFO - 'SHELL': '/bin/bash', 22:05:40 INFO - 'SHLVL': '1', 22:05:40 INFO - 'TERM': 'linux', 22:05:40 INFO - 'TMOUT': '86400', 22:05:40 INFO - 'USER': 'cltbld', 22:05:40 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:40 INFO - '_': '/tools/buildbot/bin/python'} 22:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:40 INFO - Downloading/unpacking jsonschema 22:05:40 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 22:05:40 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 22:05:40 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 22:05:40 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 22:05:40 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 22:05:40 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 22:05:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:05:42 INFO - Downloading/unpacking functools32 (from jsonschema) 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 INFO - Downloading functools32-3.2.3-2.tar.gz 22:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:05:42 INFO - warning: no files found matching '*.txt' 22:05:42 INFO - no previously-included directories found matching 'build' 22:05:42 INFO - no previously-included directories found matching 'dist' 22:05:42 INFO - no previously-included directories found matching '.git*' 22:05:42 INFO - Installing collected packages: jsonschema, functools32 22:05:42 INFO - Running setup.py install for functools32 22:05:42 INFO - warning: no files found matching '*.txt' 22:05:42 INFO - no previously-included directories found matching 'build' 22:05:42 INFO - no previously-included directories found matching 'dist' 22:05:42 INFO - no previously-included directories found matching '.git*' 22:05:42 INFO - Successfully installed jsonschema functools32 22:05:42 INFO - Cleaning up... 22:05:42 INFO - Return code: 0 22:05:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:05:42 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')]} 22:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:42 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')]} 22:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:42 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 0x7f676f8eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', '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 22:05:42 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 22:05:42 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 22:05:42 INFO - Using env: {'DISPLAY': ':0', 22:05:42 INFO - 'HOME': '/home/cltbld', 22:05:42 INFO - 'LANG': 'en_US.UTF-8', 22:05:42 INFO - 'LANGUAGE': 'en_US:en', 22:05:42 INFO - 'LOGNAME': 'cltbld', 22:05:42 INFO - 'MAIL': '/var/mail/cltbld', 22:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:42 INFO - 'MOZ_NO_REMOTE': '1', 22:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:42 INFO - 'NO_EM_RESTART': '1', 22:05:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:42 INFO - 'PWD': '/builds/slave/test', 22:05:42 INFO - 'SHELL': '/bin/bash', 22:05:42 INFO - 'SHLVL': '1', 22:05:42 INFO - 'TERM': 'linux', 22:05:42 INFO - 'TMOUT': '86400', 22:05:42 INFO - 'USER': 'cltbld', 22:05:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:42 INFO - '_': '/tools/buildbot/bin/python'} 22:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:42 INFO - Downloading/unpacking flake8 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:42 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 22:05:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:05:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:44 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 22:05:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:05:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:05:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:05:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:05:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:05:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:05:45 INFO - no previously-included directories found matching 'docs/_build' 22:05:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:05:45 INFO - Running setup.py install for pep8 22:05:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:05:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:05:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:05:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:05:45 INFO - no previously-included directories found matching 'docs/_build' 22:05:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:05:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:05:45 INFO - Cleaning up... 22:05:45 INFO - Return code: 0 22:05:45 INFO - Running post-action listener: _resource_record_post_action 22:05:45 INFO - Running post-action listener: _start_resource_monitoring 22:05:45 INFO - Starting resource monitoring. 22:05:45 INFO - ##### 22:05:45 INFO - ##### Finished create-virtualenv step (success) 22:05:45 INFO - ##### 22:05:45 INFO - ##### 22:05:45 INFO - ##### Running install step. 22:05:45 INFO - ##### 22:05:45 INFO - Running pre-action listener: _resource_record_pre_action 22:05:45 INFO - Running main action method: install 22:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:46 INFO - Reading from file tmpfile_stdout 22:05:46 INFO - Detecting whether we're running mozinstall >=1.0... 22:05:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:05:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:05:46 INFO - Reading from file tmpfile_stdout 22:05:46 INFO - Output received: 22:05:46 INFO - Usage: mozinstall [options] installer 22:05:46 INFO - Options: 22:05:46 INFO - -h, --help show this help message and exit 22:05:46 INFO - -d DEST, --destination=DEST 22:05:46 INFO - Directory to install application into. [default: 22:05:46 INFO - "/builds/slave/test"] 22:05:46 INFO - --app=APP Application being installed. [default: firefox] 22:05:46 INFO - mkdir: /builds/slave/test/build/application 22:05:46 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'] 22:05:46 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 22:06:02 INFO - Reading from file tmpfile_stdout 22:06:02 INFO - Output received: 22:06:02 INFO - /builds/slave/test/build/application/firefox/firefox 22:06:02 INFO - Running post-action listener: _resource_record_post_action 22:06:02 INFO - ##### 22:06:02 INFO - ##### Finished install step (success) 22:06:02 INFO - ##### 22:06:02 INFO - ##### 22:06:02 INFO - ##### Running run-tests step. 22:06:02 INFO - ##### 22:06:02 INFO - Running pre-action listener: _resource_record_pre_action 22:06:02 INFO - Running main action method: run_tests 22:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:02 INFO - Python 2.7.3 22:06:02 INFO - Return code: 0 22:06:02 INFO - grabbing minidump binary from tooltool 22:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:02 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 0x202e9c0>, '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 0x202f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x202fd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:02 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 22:06:02 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 22:06:02 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 22:06:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:02 INFO - Return code: 0 22:06:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:06:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:06:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:06:03 INFO - Return code: 0 22:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 22:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 22:06:03 INFO - Using env: {'DISPLAY': ':0', 22:06:03 INFO - 'HOME': '/home/cltbld', 22:06:03 INFO - 'LANG': 'en_US.UTF-8', 22:06:03 INFO - 'LANGUAGE': 'en_US:en', 22:06:03 INFO - 'LOGNAME': 'cltbld', 22:06:03 INFO - 'MAIL': '/var/mail/cltbld', 22:06:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:06:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:03 INFO - 'MOZ_NO_REMOTE': '1', 22:06:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:03 INFO - 'NO_EM_RESTART': '1', 22:06:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:03 INFO - 'PWD': '/builds/slave/test', 22:06:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:06:03 INFO - 'SHELL': '/bin/bash', 22:06:03 INFO - 'SHLVL': '1', 22:06:03 INFO - 'TERM': 'linux', 22:06:03 INFO - 'TMOUT': '86400', 22:06:03 INFO - 'USER': 'cltbld', 22:06:03 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742', 22:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:03 INFO - '_': '/tools/buildbot/bin/python'} 22:06:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 22:06:03 INFO - mozversion application_buildid: 20160602205209 22:06:03 INFO - mozversion application_changeset: a39da695528a04705d25d0159d6250917c95eee0 22:06:03 INFO - mozversion application_display_name: Nightly 22:06:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:06:03 INFO - mozversion application_name: Firefox 22:06:03 INFO - mozversion application_remotingname: firefox 22:06:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:06:03 INFO - mozversion application_vendor: Mozilla 22:06:03 INFO - mozversion application_version: 49.0a1 22:06:03 INFO - mozversion platform_buildid: 20160602205209 22:06:03 INFO - mozversion platform_changeset: a39da695528a04705d25d0159d6250917c95eee0 22:06:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:06:03 INFO - mozversion platform_version: 49.0a1 22:06:03 INFO - using testdate: 1464930363 22:06:03 INFO - actual date: 1464930363 22:06:03 INFO - starting webserver on 'localhost:56410' 22:06:03 INFO - SUITE-START | Running 2 tests 22:06:03 INFO - TEST-START | dromaeo_css 22:06:03 INFO - operating with platform_type : linux_ 22:06:03 INFO - Initialising browser for dromaeo_css test... 22:06:03 INFO - TEST-INFO | started process 21189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzi8cUx/profile http://localhost:56410/getInfo.html) 22:06:06 INFO - PROCESS | 21189 | __metrics Screen width/height:1600/1200 22:06:06 INFO - PROCESS | 21189 | colorDepth:24 22:06:06 INFO - PROCESS | 21189 | Browser inner width/height: 1024/697 22:06:06 INFO - PROCESS | 21189 | __metrics 22:06:07 ERROR - PROCESS | 21189 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:06:08 INFO - TEST-INFO | 21189: exit 0 22:06:08 INFO - Browser initialized. 22:06:08 INFO - Running cycle 1/1 for dromaeo_css test... 22:06:08 INFO - TEST-INFO | started process 21271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzi8cUx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:20 INFO - PROCESS | 21271 | RSS: Main: 157392896 22:06:20 INFO - PROCESS | 21271 | 22:07:12 INFO - PROCESS | 21271 | 1464930432311 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] 22:09:23 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56410/tests/dromaeo/cssquery-ext.html) 22:09:25 INFO - PROCESS | 21271 | RSS: Main: 232013824 22:09:25 INFO - PROCESS | 21271 | 22:12:27 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-ext.html (next: http://localhost:56410/tests/dromaeo/cssquery-jquery.html) 22:12:28 INFO - PROCESS | 21271 | RSS: Main: 240881664 22:12:28 INFO - PROCESS | 21271 | 22:15:30 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56410/tests/dromaeo/cssquery-mootools.html) 22:15:30 INFO - PROCESS | 21271 | RSS: Main: 234184704 22:15:30 INFO - PROCESS | 21271 | 22:18:33 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56410/tests/dromaeo/cssquery-prototype.html) 22:18:33 INFO - PROCESS | 21271 | RSS: Main: 230359040 22:18:33 INFO - PROCESS | 21271 | 22:21:36 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56410/tests/dromaeo/cssquery-yui.html) 22:21:37 INFO - PROCESS | 21271 | RSS: Main: 218017792 22:21:37 INFO - PROCESS | 21271 | 22:24:39 INFO - PROCESS | 21271 | Cycle 1(1): loaded http://localhost:56410/tests/dromaeo/cssquery-yui.html (next: http://localhost:56410/tests/dromaeo/cssquery-dojo.html) 22:24:40 INFO - PROCESS | 21271 | RSS: Main: 298541056 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | __start_tp_report 22:24:40 INFO - PROCESS | 21271 | _x_x_mozilla_page_load 22:24:40 INFO - PROCESS | 21271 | _x_x_mozilla_page_load_details 22:24:40 INFO - PROCESS | 21271 | |i|pagename|runs| 22:24:40 INFO - PROCESS | 21271 | |0;dojo.html;2673.9866300668496;2681.785457163427;2715.5519339309017;2718.8368697878122;2731.4537092581477;4650.139724151026;4672.742999135046;4729.077829823186;4785.090832741781;4854.684445511044;4694.765261736917;4719.386479757635;4721.244600863863;4754.168020596399;4785.736784476852;4145;4264.2111209426275;4395.428594282739;4421.57994990477;4577.633789296865;4396.032872768002;4410.140022695576;4423.270160423543;4427.71219870709;4443.666724995638;4271.786410679453;4310.137972405531;4373.081652852873;4408.515063343029;4414.889627759299;4272.722273052242;4275.144971005811;4275.5296917339065;4310.762908040057;4372.103718737651;4305.440292761952;4312.892177695551;4324.762138082404;4345.913081738379;4372.956270437318;4572.817087316504;4590.265557510782;4677.929831052537;4692.178868697964;4695.225287827504;5121.743912804345;5130.9230361544605;5147.459516750725;5147.6911385317;5150.510701483353;5143.562797162245;5177.48225177483;5196.090684130262;5197.038547868657;5247.2653828464045;4614.400127983375;4711.905761884812;4751.95248047522;4756.048790241965;4779.091972525209;5253.658512196695;5283.260343551906;5283.656562323436;5286.97356513215;5289.127293996447;4851.951480485221;5004.149294619923;5006.974965125224;5052;5057.620678449131;5047.772850221749;5055.443901170868;5057.797688092435;5113.284140220372;5138.4604616514725;5070.619703522177;5087.669301495465;5088.618353623493;5091.3381260436645;5137.768800403991;4909.214525675875;4998.275250088789;5054.949450505522;5068.341115654941;5070.416902056237;4346.7609281489185;4358.3244597087505;4374.715643483163;4426.070525189746;4430.667699922455;3793.070697679031;3804.885853424402;3813.504244448204;3838.692904567628;3846.6922646188236;4297.05464797744;4341.978290108529;4420.049689316812;4433.1577000369825;4457.175422546774;5056.949430505648;5112.974435127875;5118;5124.846254612367;5134.589232861362;4426.44669416323;4434.866953991385;4435.179491794029;4435.3125265583885;4478.507364189936;4457.732536047847;4517.932231016537;4649.163150632919;4650.581447669726;4687.1328804171335;4750.334953106637;4808.663393562417;4813.278008298643;4826.710397376169;4841.95158048415;5128.974355128201;5129.076766181974;5136.486351364833;5139.794408223629;5188.014277287303;4689.531046895283;4718.693284936468;4775.546323099439;4832.371791667061;4884.65807393479;4999.075171093359;5035.244713293036;5046.142155833444;5054.2671312660195;5099.26060721186;5091.643584949166;5095.15929871567;5107.770150343169;5110.540051395392;5158.123119069842;4815.927761083657;4928.482509336612;4933.457319694759;4966.577840883565;4999.250112483157;5017.648764586444;5039.042581909425;5044.1424957756535;5044.167712327425;5066.797328106834;4963.602911767122;4964.106460837084;4994.101061808764;4999.850004499871;5010.549050585465;4864.683795553277;4985.252212168204;5037;5053.014662140848;5111.105556527518;4933.654644174873;4935.703857768545;4947.233178857283;4954.975225123995;4998.975005124951 22:24:40 INFO - PROCESS | 21271 | |1;ext.html;579.2122713110172;600.4175949329152;602.0367412140573;605.2434456928839;620.515997521933;28003.719962800365;28457.573136402945;30357.392852142944;30369;30379.54430683539;30126.397472050547;30777;41412.17175656485;42053.36919946203;42091.57908420915;7625.199354067812;7822.452428330019;7840.333571646417;7844.72543460979;7856.450048496579;7682.807929801772;7802.102758182825;7804.336631386339;7809.531428114331;7812.765617031499;7802.921970780305;7854.371650267994;7856.72501462449;7858.607069646495;7865.68537258509;7767.650455729506;7794.025746781653;7804.531728096333;7809.804754881116;7815.335696465807;7712.190220026929;7791.259830316111;7804.882926756103;7811.375089992844;7811.687532498693;37559.436608450895;37952.620473795185;37959.81020094882;38039.23921521582;38054.61945380566;2972.5838382626234;3062.5865508156335;3099.1942095055224;3313.4035873542753;3316.718078963291;28704;29051.56422653662;29744.70255297441;29993.550096748568;30148.8492557538;3366.410878096348;3402.8468718907707;3406.914827129317;3428.9828550857337;3451.8446669899913;264023;270433;270665;271211.28788712254;271341;56555.71722141363;57532.42467575271;57557;57579.712101439225;57595;33842.76965379328;34977.825110874284;34999.30001399958;36728.63271367252;36821;15693.136877471848;15829.129397883227;15894.205289735466;15900.52298431049;15901.920490397706;10950.835737463944;10981.780364392667;10988.285761425483;10991.340519568852;11011.559537618567;18687;18719.906400468182;18737.1568279425;18754.906225468785;18796.718049229272;6931.445484361239;6956.373926346552;7072.3634872861685;7175.497715159889;7244.97623862994;844.3751623798345;886.9246114080375;921.3366376209119;931.9487428191514;1027.941220542842;27334.726652733614;28485.857570712014;28534.714652853207;28850.27874303159;29044;992.2359782967075;1000.6597756762735;1010.5338688299058;1038.1694644284541;1046.1839764983324;14830;15091.320890559951;15114.622134446727;15229.695406091816;15359.692806144038;398559;402820;403763;403784;404533.569310915;51173;51435.228471572955;51825;52005.479945200066;52128;1116.0569318925495;1155.5608868629868;1219.8353222314875;1229.7601967616229;1240.975180496385;28346;28580;30475;30818.84590577033;30933.690663093083;43435.78282108569;44020.33969490396;44061.33907991447;44320.55679443164;44389.11221775611;354358;361161;361878;362620;362627;34925.82537087298;39424.59313915137;39971.80014100027;40472;40667.1866562673;43562.782186088865;43773.78113109541;43892;44074.11851762883;44530.105543139594;37211.441828373136;37496.6250337504;37772.81113594415;37878.2424351517;38013.42979855249;35694.64305356913;36110.09724756902;36125.45811812877;36479;36537.634623654485;31681.841590791897;31864.36271274608;35533.64466355303;37150.44274335833;37485.81257093697;31577.52633710542;33782.263764048206;33936.32127357488;34194.14514637154;34401;25524.23427297184;25635.615465767656;26013.869930650224;26107;26287.73712262853 22:24:40 INFO - PROCESS | 21271 | |2;jquery.html;366.7780992499537;370.96475834795694;377.86963497593337;380.38757600263585;392.81930312056454;15889.2849821758;17018.489445316645;17040.829591704078;17196.140192990348;17466.301347946097;16104.677906441864;16486.25811838467;17180.484585462425;17196.82803171968;17199.914000430013;4872.07430588188;4967.354243948282;5055.368078990126;5056.544910958013;5057.620678449112;3671.3575124353133;3827.3876179811236;4984.725840078794;5414.810481633143;5699.772009119651;5713.542916566686;5715.485606295432;5739.684317362543;5744.31068271808;5749.626274292178;5573.08044172711;5640.520555752766;5707.1724599933195;5728.79949201778;5755.712214389264;3344.6320904700465;4793.49668284832;5040.697558146487;5094.414142373637;5097.082525145473;19854.602907941906;20292.492687682927;20367.898160509252;20655.17379304826;20687.79312206874;1806.9367572135043;1817.7546031285872;1936.9806301937083;2094.853360264782;2324.2097686786406;6380.234371875358;6403.8399040023905;12846.293453860035;12950.805737913939;13056.738865222642;1668.883178177528;1721.208244207666;1749.3439960014994;1750.9212085456059;1751.2382113780545;65456.67271663659;65643;66045;67204.6639766803;67577.3242267581;9417.90582094184;9521.809563808685;9719.271054670928;9950.502474876228;10046.876093192379;13260.668483287995;13372.398242079129;13382.46470141203;13404.061715679809;13408.932955335162;7589.658465369072;7614.390848732088;7656.655450504741;7872;7873.724419645399;7599.4680372373405;7607.505512141693;7628.275313845176;7723.189065148191;7754.030746156731;6142.508599312045;6271.843203919847;6348.841278968062;6386.265579458329;6488.967555162194;1567.2242240091227;1646.3167785369178;1708.265445858268;1734.3322820713863;1742.8605711543046;795.5266616363255;856.9775330220674;867.5402036920442;914.794171311463;949.8527728202001;16953;17009.82371147898;17489.30042798299;17598;17801.46595602134;869.4348673362365;920.8940971788196;925.2459245714721;933.2953620016843;965.8792650918635;9771.97394273591;10181.33821301613;10194.133498652549;10270.281080324306;10339.586416543405;329635;329828;330368;343483;348764;64230;65751.34248657548;68087.31912680909;68912.3108768906;69827.65086174537;903.6069309850104;952.1050212799946;960.6301573893961;965.9178969787506;971.0823272008026;15644.608884777972;15649;15837.287322070764;15926.203689815231;16089.075838178895;27495.587566186125;33305.83347083346;34065.65934340724;35047.299054019284;35051.82474087613;45243.77378113088;46418.84225311312;48730;49113;49567.25649115194;6172.753089876354;7592.354649854824;7815.179406162271;7835.529868207926;7953.5227886327;8764.824703506021;9318.254539636948;10943.398113103856;11328.660140195807;11639.941800290944;9694.5152742364;9985.950070249603;9986.800263994533;10152.187824973837;10432.113270372103;10154.949225253826;10213.33613315132;10391;10545.10366618846;10762.892371076188;10408.531616077446;10503.737406564896;10552.947235263775;10635.255532112988;10941.28881622692;6773.322667733188;7026.824329391806;7129.572225666477;7743.186965368555;7887.211278872067;9267.53662316895;9400.247980161708;9490.762730931505;9708.805823883624;9717.174040206379 22:24:40 INFO - PROCESS | 21271 | |3;mootools.html;1284.710940038491;1429.0925262458338;1441.4738620403552;1556.2141118735044;1753.5090174751067;6471.644059576727;6934.30656934307;7191.137063552368;7206.60363679998;7376.446766492508;7226.132864056321;7227.024351712517;7273.527220730649;7282.526635768671;7286.854262914739;2115.947101322468;2139.3902737719754;2146.2380854796556;2146.8497205195645;2148.2373757316163;2056.064490656755;2058.259026750369;2070.4202823209525;2122.182959560565;2181.7490988536365;2144.5603651251454;2161.778595093775;2166.80498755112;2170.8046275835172;2189.343197040889;2114.9259775907844;2120.7561130469885;2122.9256976005768;2124.4370241885913;2130.4141361125658;2051.200031987511;2114.3339847948;2117.026167962739;2117.7670456249953;2120.5016821047043;7175.533590316612;7179.389751871045;7222.133343998754;7227.060482137288;7234.2765723427765;4424.424824772791;4557.680962332638;4558.133954548625;4562.406887104688;4565.315202719586;2805.5370863807448;2885.350796070867;2943.470175368433;2943.5143201371748;2943.7350638442645;877.4209022045483;887.5340446265698;896.5024411451642;914.1909410171979;930.432436213909;9700.369475984038;9782.119609235204;9805.215582753362;9808.950955245178;9810.65662701802;3751.737378383487;3852.1910398816003;3910.1006768443417;3914.8629797956933;3921.254961557295;7577.355924746458;7646.541207527455;7659.770206893802;7659.885101723478;7662.0422447194105;4354.194474022316;4354.216241076637;4360.934585981213;4364.869053928387;4366.694331396771;3601.1537288737127;4031.737937034083;4065.4918135233097;4117.91764164721;4199.97900010498;3316.5688460499982;3318.7013168814924;3326.478046442893;3420.863165473403;3420.880269190566;1030.4332617060586;1049.506731836036;1052.394872948058;1058.5342449322427;1062.86182796236;664.5315052887703;725.8874874394477;773.6866569039548;829.088003196479;871.1288711288711;4966.826161084345;4975.502449754996;4990.201567749143;5022.146235140035;5022.949770502249;433.8828516300581;439.13709560712715;442.22831159626656;446.6315289885857;592.3129170162676;3454;3589.4436362363876;3597.964020359815;3663.780173189617;3677.4483827425565;8556.401051926305;9003.909960900439;9037.638494460149;9086.136817002374;9091.727248182566;8932.910670893209;8962.103789620985;8979.326550508738;8982.326325525612;9001.54992250385;6474.741010359627;6980.965095174492;7010.964945175241;7064.894026589605;7089.042979197742;5261.89476210466;5263.868403289948;5339.7063161526485;5403.567714582745;5507.449255074461;7970;8184.386171037077;8278.254957053894;8403.159684031549;8413.20074592937;8423.115572865006;8557.529335886586;8604.78488037779;8626.78433039154;8627.784305392415;5774.56690748204;5910.438508341701;6412.198475190601;6533.934660653333;6536.607803531803;6355.52333574993;6409.166808314889;6883.346082122191;6887.586744795329;6986.790396288119;6027.427394397525;6374.968125159345;6411.93588064132;6537.215534135934;6554.737810487527;6371.776987805404;6469.2560355557835;6474.449671777952;6548.508861835287;6584.473242140713;5887.470127688529;5900.085486749561;5941.613795103304;5947.732352044255;5952.76188952437;4404.7357158570585;4405.625521830552;4485.215087359764;4509.842155524541;4530.796114174936;4374.650027997816;4435.977820110878;4474.351219073151;4500.437445319335;4515.322701594787 22:24:40 INFO - PROCESS | 21271 | |4;prototype.html;293.0928775440014;302.4329382407985;309.50942755732154;310.102253974743;312.02181162056945;4738.407699037621;4797.184478638631;4812.831550895719;4832.710037397758;4839.46765855756;4487.192305385038;4597.034622729222;4598.356230127785;4598.44818621765;4735.431748190222;3598.8560457581666;3661.279198776584;3678.5953545110015;3754.943675844851;3811.6950643948417;3761.8683346082894;3771.679407250387;3789;3789.3747531657245;3797.7721336719746;3700.111973126444;3767.5478942526934;3775.8867233983024;3788.753731007476;3801.8479260829536;3764.661180493769;3785.1861849702173;3791.5971090696617;3793.4499497572697;3805.562360328556;3346.297277571713;3355.748318876094;3360.0423879194555;3373.1567108222944;3376.1728376547658;4601.309803529464;4616.407339467878;4618.607418369409;4620.260758278693;4644.558766917137;4181.038361176916;4261.275583150841;4305.117450922553;4317.373980772802;4345.69580129391;4988.251762235694;5006.424261209934;5011.949880501221;5028.296038554606;5041.269015992661;3647.1611529348397;3665.560132784083;3666.6699997000396;3681.7606855554573;3687.46531752897;4855.368802055746;5168.043911876277;5195.584353251731;5213.296205012312;5219.295395121648;3577.910552236163;3582.731295152874;3589.120665436985;3593.2813437312643;3596.2987217492337;3597.6508809196553;3615.258871931248;3654.250878569887;3655.1775850433974;3661.4804855984466;4217.536071032184;4233.640140588023;4239.42767726353;4239.491261048633;4244.660427165819;4786.688865223749;4923.458419573915;4973.6269779765935;5005.27423523587;5075.3402057733;1298.363801737142;1307.320193499375;1319.142557337719;1330.9334533273297;1332.9666758330925;838.6707069295236;903.8056817784208;926.8887733472027;938.7842743646994;943.1605870775015;4399.868003959887;4412.477695195153;4423.51341352451;4428.335749637516;4443.844465443694;5043.949560504421;5052.898942021213;5086.92369614456;5111.488851114858;5123.846284611468;4240.346264956669;4311.76285304305;4323.891902702458;4365.716228445141;4397.252467080604;2687.951698837455;2697.5414179589516;2708.837469751821;2717.8449159107695;2725.727427257298;4760.785764640599;4786;4798.688085274446;4847.903041939212;4852.878678033077;4965.453800081988;5139.535232458731;5150.793968241303;5189.481051894781;5201.947980520222;4762.976185119052;4769.451513075971;4779.569838714532;4785.641076919245;4786.712797232177;5007.198848184273;5027.3464449621315;5061.443241243386;5062.772175252049;5083.186690129562;4728.38530990969;5021.196608542616;5025;5057.190849464068;5069.898602028013;4850.80596776125;4869.756512174306;4874.0511990245395;4886.486918873467;4900.607951363953;3633.945490817587;3657.341678497896;3669.944950825793;3670.5595328560744;3680.55833299995;5108.233764935289;5153.227015947488;5160.451864440728;5248;5263.789208532349;5020.472850350807;5021;5024.648274620888;5063;5067.479756073089;5004.2493625956395;5012.624053195952;5035.471275516018;5047.2429135629945;5073.543381095719;4707.8587642370785;4773.355596994361;4956.182229932165;4993.226049962262;4995.47547507521;4907.509249075207;5032.220005899091;5044.419891712353;5095.566876815512;5105.7191854448365;4967.602591792575;4978.228374601942;4981.476944920876;4993.925091123709;5005.899882002413 22:24:40 INFO - PROCESS | 21271 | |5;yui.html;613.7544982007197;615.9260888693358;628.8176428835637;637.0889627832202;721.9855602887942;12244.938775306122;13021.60935171944;13064.738705225902;13076.150050246728;13078.21530708157;11889.881101188985;12881.098323117385;13054.934725326384;13072;14360.640983975405;803.9959800201005;818.1327792539897;820.0692214336741;820.6225136437246;823.6622984576325;819.5820131732829;820.5117954816905;820.5445977482494;822.7737372222626;823.6170180865896;874.9125087491263;876.1457578860624;877.0221203358242;878.3895192840978;878.4377998081233;870.5864714260739;870.5951732444412;870.7648934787635;871.3943809052711;871.4945331707594;636.7102968149527;637.9202599675041;638.0684201066449;638.4923985431593;638.5881106686162;12530.74938501234;12838.422270997828;12865.099443038991;12884.226946383247;12939.094263401566;13309.134906231064;13378.86621133786;13438.86561134386;13656.93171534136;13825;9787.434010558347;9937.552810123561;9947.1544918682;9960.95019524905;10037.648682296158;3299.96700032999;3334.499825026241;3342.88299909502;3371.7471189660628;3459.705924996353;56594.71702641502;56640;56667;56703.14945275824;59914.70042649759;10266.281360304856;10378.325408848401;10402.843957340647;10459.424731639681;10702.892971070345;18939;19198.424047278604;19278.71081933772;19533.413997580094;19562.804371956383;6782.321767823179;6878.58728476293;6879.89680154788;6926;7092.113485814273;3676.17286110623;3758.3986562149926;3887.1059656279213;3894.571597124314;3905.9804700976883;2849.1167738001286;2985.7611391088662;2989.088328059919;2990.820550766961;2992.8503574821607;497.2069549069229;497.659103514094;508.1234869849554;510.2983397827987;511.92065229889425;437.72860826287905;562.0669688317373;569.1349149293134;574.9482546570829;579.1880420558214;8276.08963014068;8277.710280140287;8292;8331.33349332052;8553.700620478254;532.8348212054276;534.9946500534945;535.5367607019978;539.8326518779191;548.9215131572455;3994.141259629194;4003.3594624859884;4044.2315959967514;4072.6538244249573;4097.7541347519245;64987;66392.66803665951;84211.84199343002;90650;93650;48233.27650085252;54050;56833.7158314214;62708.686456567426;64824.67587662126;66720.6663966677;68312.3168768316;68497.3150268491;69113;69645.65177174183;8296.585170741559;8341.95622072596;8366.958165209378;8709.782255443411;8883.466991980502;43131.353029703954;43879.122417552106;44351.556484436034;44631.776841115585;45263.094738104395;61449;61493;61889;61892;62447.375526245974;11615.548056492938;11774.587889423829;11782.17524773292;11973.401329933295;12036.157468977088;12682.809757853454;12733.172343797623;12903.290319032169;12959.611211663665;13259.337033148111;11671.35807530594;11784.705382365166;11928.642140735792;11956.58151964677;12297.938510307391;11637.301761894314;11966.880331196924;12064.638060858188;12173;12683;11569.768604627692;11781;12063.457144428347;12212.32832194238;12551.246925184518;9414.914607403889;9760.365576237522;9914.553845076845;10333.328333658288;10341.793164136825;9489.66786162481;9891.35706179096;9894.356866803635;9967.850482242628;9977 22:24:40 INFO - PROCESS | 21271 | __end_tp_report 22:24:40 INFO - PROCESS | 21271 | __start_cc_report 22:24:40 INFO - PROCESS | 21271 | _x_x_mozilla_cycle_collect,1044 22:24:40 INFO - PROCESS | 21271 | __end_cc_report 22:24:40 INFO - PROCESS | 21271 | __startTimestamp1464931480156__endTimestamp 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | ------- Summary: start ------- 22:24:40 INFO - PROCESS | 21271 | Number of tests: 6 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#0] dojo.html Cycles:165 Average:4717.42 Median:4832.37 stddev:495.99 (10.3%) stddev-sans-first:470.90 22:24:40 INFO - PROCESS | 21271 | Values: 2674.0 2681.8 2715.6 2718.8 2731.5 4650.1 4672.7 4729.1 4785.1 4854.7 4694.8 4719.4 4721.2 4754.2 4785.7 4145.0 4264.2 4395.4 4421.6 4577.6 4396.0 4410.1 4423.3 4427.7 4443.7 4271.8 4310.1 4373.1 4408.5 4414.9 4272.7 4275.1 4275.5 4310.8 4372.1 4305.4 4312.9 4324.8 4345.9 4373.0 4572.8 4590.3 4677.9 4692.2 4695.2 5121.7 5130.9 5147.5 5147.7 5150.5 5143.6 5177.5 5196.1 5197.0 5247.3 4614.4 4711.9 4752.0 4756.0 4779.1 5253.7 5283.3 5283.7 5287.0 5289.1 4852.0 5004.1 5007.0 5052.0 5057.6 5047.8 5055.4 5057.8 5113.3 5138.5 5070.6 5087.7 5088.6 5091.3 5137.8 4909.2 4998.3 5054.9 5068.3 5070.4 4346.8 4358.3 4374.7 4426.1 4430.7 3793.1 3804.9 3813.5 3838.7 3846.7 4297.1 4342.0 4420.0 4433.2 4457.2 5056.9 5113.0 5118.0 5124.8 5134.6 4426.4 4434.9 4435.2 4435.3 4478.5 4457.7 4517.9 4649.2 4650.6 4687.1 4750.3 4808.7 4813.3 4826.7 4842.0 5129.0 5129.1 5136.5 5139.8 5188.0 4689.5 4718.7 4775.5 4832.4 4884.7 4999.1 5035.2 5046.1 5054.3 5099.3 5091.6 5095.2 5107.8 5110.5 5158.1 4815.9 4928.5 4933.5 4966.6 4999.3 5017.6 5039.0 5044.1 5044.2 5066.8 4963.6 4964.1 4994.1 4999.9 5010.5 4864.7 4985.3 5037.0 5053.0 5111.1 4933.7 4935.7 4947.2 4955.0 4999.0 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#1] ext.html Cycles:180 Average:49503.26 Median:362623.50 stddev:91937.86 (25.4%) stddev-sans-first:92122.39 22:24:40 INFO - PROCESS | 21271 | Values: 579.2 600.4 602.0 605.2 620.5 28003.7 28457.6 30357.4 30369.0 30379.5 30126.4 30777.0 41412.2 42053.4 42091.6 7625.2 7822.5 7840.3 7844.7 7856.5 7682.8 7802.1 7804.3 7809.5 7812.8 7802.9 7854.4 7856.7 7858.6 7865.7 7767.7 7794.0 7804.5 7809.8 7815.3 7712.2 7791.3 7804.9 7811.4 7811.7 37559.4 37952.6 37959.8 38039.2 38054.6 2972.6 3062.6 3099.2 3313.4 3316.7 28704.0 29051.6 29744.7 29993.6 30148.8 3366.4 3402.8 3406.9 3429.0 3451.8 264023.0 270433.0 270665.0 271211.3 271341.0 56555.7 57532.4 57557.0 57579.7 57595.0 33842.8 34977.8 34999.3 36728.6 36821.0 15693.1 15829.1 15894.2 15900.5 15901.9 10950.8 10981.8 10988.3 10991.3 11011.6 18687.0 18719.9 18737.2 18754.9 18796.7 6931.4 6956.4 7072.4 7175.5 7245.0 844.4 886.9 921.3 931.9 1027.9 27334.7 28485.9 28534.7 28850.3 29044.0 992.2 1000.7 1010.5 1038.2 1046.2 14830.0 15091.3 15114.6 15229.7 15359.7 398559.0 402820.0 403763.0 403784.0 404533.6 51173.0 51435.2 51825.0 52005.5 52128.0 1116.1 1155.6 1219.8 1229.8 1241.0 28346.0 28580.0 30475.0 30818.8 30933.7 43435.8 44020.3 44061.3 44320.6 44389.1 354358.0 361161.0 361878.0 362620.0 362627.0 34925.8 39424.6 39971.8 40472.0 40667.2 43562.8 43773.8 43892.0 44074.1 44530.1 37211.4 37496.6 37772.8 37878.2 38013.4 35694.6 36110.1 36125.5 36479.0 36537.6 31681.8 31864.4 35533.6 37150.4 37485.8 31577.5 33782.3 33936.3 34194.1 34401.0 25524.2 25635.6 26013.9 26107.0 26287.7 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#2] jquery.html Cycles:180 Average:22325.01 Median:48921.50 stddev:55583.10 (113.6%) stddev-sans-first:55714.58 22:24:40 INFO - PROCESS | 21271 | Values: 366.8 371.0 377.9 380.4 392.8 15889.3 17018.5 17040.8 17196.1 17466.3 16104.7 16486.3 17180.5 17196.8 17199.9 4872.1 4967.4 5055.4 5056.5 5057.6 3671.4 3827.4 4984.7 5414.8 5699.8 5713.5 5715.5 5739.7 5744.3 5749.6 5573.1 5640.5 5707.2 5728.8 5755.7 3344.6 4793.5 5040.7 5094.4 5097.1 19854.6 20292.5 20367.9 20655.2 20687.8 1806.9 1817.8 1937.0 2094.9 2324.2 6380.2 6403.8 12846.3 12950.8 13056.7 1668.9 1721.2 1749.3 1750.9 1751.2 65456.7 65643.0 66045.0 67204.7 67577.3 9417.9 9521.8 9719.3 9950.5 10046.9 13260.7 13372.4 13382.5 13404.1 13408.9 7589.7 7614.4 7656.7 7872.0 7873.7 7599.5 7607.5 7628.3 7723.2 7754.0 6142.5 6271.8 6348.8 6386.3 6489.0 1567.2 1646.3 1708.3 1734.3 1742.9 795.5 857.0 867.5 914.8 949.9 16953.0 17009.8 17489.3 17598.0 17801.5 869.4 920.9 925.2 933.3 965.9 9772.0 10181.3 10194.1 10270.3 10339.6 329635.0 329828.0 330368.0 343483.0 348764.0 64230.0 65751.3 68087.3 68912.3 69827.7 903.6 952.1 960.6 965.9 971.1 15644.6 15649.0 15837.3 15926.2 16089.1 27495.6 33305.8 34065.7 35047.3 35051.8 45243.8 46418.8 48730.0 49113.0 49567.3 6172.8 7592.4 7815.2 7835.5 7953.5 8764.8 9318.3 10943.4 11328.7 11639.9 9694.5 9986.0 9986.8 10152.2 10432.1 10154.9 10213.3 10391.0 10545.1 10762.9 10408.5 10503.7 10552.9 10635.3 10941.3 6773.3 7026.8 7129.6 7743.2 7887.2 9267.5 9400.2 9490.8 9708.8 9717.2 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#3] mootools.html Cycles:180 Average:4820.98 Median:5301.79 stddev:2633.14 (49.7%) stddev-sans-first:2627.12 22:24:40 INFO - PROCESS | 21271 | Values: 1284.7 1429.1 1441.5 1556.2 1753.5 6471.6 6934.3 7191.1 7206.6 7376.4 7226.1 7227.0 7273.5 7282.5 7286.9 2115.9 2139.4 2146.2 2146.8 2148.2 2056.1 2058.3 2070.4 2122.2 2181.7 2144.6 2161.8 2166.8 2170.8 2189.3 2114.9 2120.8 2122.9 2124.4 2130.4 2051.2 2114.3 2117.0 2117.8 2120.5 7175.5 7179.4 7222.1 7227.1 7234.3 4424.4 4557.7 4558.1 4562.4 4565.3 2805.5 2885.4 2943.5 2943.5 2943.7 877.4 887.5 896.5 914.2 930.4 9700.4 9782.1 9805.2 9809.0 9810.7 3751.7 3852.2 3910.1 3914.9 3921.3 7577.4 7646.5 7659.8 7659.9 7662.0 4354.2 4354.2 4360.9 4364.9 4366.7 3601.2 4031.7 4065.5 4117.9 4200.0 3316.6 3318.7 3326.5 3420.9 3420.9 1030.4 1049.5 1052.4 1058.5 1062.9 664.5 725.9 773.7 829.1 871.1 4966.8 4975.5 4990.2 5022.1 5022.9 433.9 439.1 442.2 446.6 592.3 3454.0 3589.4 3598.0 3663.8 3677.4 8556.4 9003.9 9037.6 9086.1 9091.7 8932.9 8962.1 8979.3 8982.3 9001.5 6474.7 6981.0 7011.0 7064.9 7089.0 5261.9 5263.9 5339.7 5403.6 5507.4 7970.0 8184.4 8278.3 8403.2 8413.2 8423.1 8557.5 8604.8 8626.8 8627.8 5774.6 5910.4 6412.2 6533.9 6536.6 6355.5 6409.2 6883.3 6887.6 6986.8 6027.4 6375.0 6411.9 6537.2 6554.7 6371.8 6469.3 6474.4 6548.5 6584.5 5887.5 5900.1 5941.6 5947.7 5952.8 4404.7 4405.6 4485.2 4509.8 4530.8 4374.7 4436.0 4474.4 4500.4 4515.3 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#4] prototype.html Cycles:180 Average:4151.76 Median:4731.91 stddev:1187.24 (25.1%) stddev-sans-first:1154.71 22:24:40 INFO - PROCESS | 21271 | Values: 293.1 302.4 309.5 310.1 312.0 4738.4 4797.2 4812.8 4832.7 4839.5 4487.2 4597.0 4598.4 4598.4 4735.4 3598.9 3661.3 3678.6 3754.9 3811.7 3761.9 3771.7 3789.0 3789.4 3797.8 3700.1 3767.5 3775.9 3788.8 3801.8 3764.7 3785.2 3791.6 3793.4 3805.6 3346.3 3355.7 3360.0 3373.2 3376.2 4601.3 4616.4 4618.6 4620.3 4644.6 4181.0 4261.3 4305.1 4317.4 4345.7 4988.3 5006.4 5011.9 5028.3 5041.3 3647.2 3665.6 3666.7 3681.8 3687.5 4855.4 5168.0 5195.6 5213.3 5219.3 3577.9 3582.7 3589.1 3593.3 3596.3 3597.7 3615.3 3654.3 3655.2 3661.5 4217.5 4233.6 4239.4 4239.5 4244.7 4786.7 4923.5 4973.6 5005.3 5075.3 1298.4 1307.3 1319.1 1330.9 1333.0 838.7 903.8 926.9 938.8 943.2 4399.9 4412.5 4423.5 4428.3 4443.8 5043.9 5052.9 5086.9 5111.5 5123.8 4240.3 4311.8 4323.9 4365.7 4397.3 2688.0 2697.5 2708.8 2717.8 2725.7 4760.8 4786.0 4798.7 4847.9 4852.9 4965.5 5139.5 5150.8 5189.5 5201.9 4763.0 4769.5 4779.6 4785.6 4786.7 5007.2 5027.3 5061.4 5062.8 5083.2 4728.4 5021.2 5025.0 5057.2 5069.9 4850.8 4869.8 4874.1 4886.5 4900.6 3633.9 3657.3 3669.9 3670.6 3680.6 5108.2 5153.2 5160.5 5248.0 5263.8 5020.5 5021.0 5024.6 5063.0 5067.5 5004.2 5012.6 5035.5 5047.2 5073.5 4707.9 4773.4 4956.2 4993.2 4995.5 4907.5 5032.2 5044.4 5095.6 5105.7 4967.6 4978.2 4981.5 4993.9 5005.9 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - PROCESS | 21271 | [#5] yui.html Cycles:180 Average:16276.38 Median:533.91 stddev:21487.96 (4024.6%) stddev-sans-first:21516.05 22:24:40 INFO - PROCESS | 21271 | Values: 613.8 615.9 628.8 637.1 722.0 12244.9 13021.6 13064.7 13076.2 13078.2 11889.9 12881.1 13054.9 13072.0 14360.6 804.0 818.1 820.1 820.6 823.7 819.6 820.5 820.5 822.8 823.6 874.9 876.1 877.0 878.4 878.4 870.6 870.6 870.8 871.4 871.5 636.7 637.9 638.1 638.5 638.6 12530.7 12838.4 12865.1 12884.2 12939.1 13309.1 13378.9 13438.9 13656.9 13825.0 9787.4 9937.6 9947.2 9961.0 10037.6 3300.0 3334.5 3342.9 3371.7 3459.7 56594.7 56640.0 56667.0 56703.1 59914.7 10266.3 10378.3 10402.8 10459.4 10702.9 18939.0 19198.4 19278.7 19533.4 19562.8 6782.3 6878.6 6879.9 6926.0 7092.1 3676.2 3758.4 3887.1 3894.6 3906.0 2849.1 2985.8 2989.1 2990.8 2992.9 497.2 497.7 508.1 510.3 511.9 437.7 562.1 569.1 574.9 579.2 8276.1 8277.7 8292.0 8331.3 8553.7 532.8 535.0 535.5 539.8 548.9 3994.1 4003.4 4044.2 4072.7 4097.8 64987.0 66392.7 84211.8 90650.0 93650.0 48233.3 54050.0 56833.7 62708.7 64824.7 66720.7 68312.3 68497.3 69113.0 69645.7 8296.6 8342.0 8367.0 8709.8 8883.5 43131.4 43879.1 44351.6 44631.8 45263.1 61449.0 61493.0 61889.0 61892.0 62447.4 11615.5 11774.6 11782.2 11973.4 12036.2 12682.8 12733.2 12903.3 12959.6 13259.3 11671.4 11784.7 11928.6 11956.6 12297.9 11637.3 11966.9 12064.6 12173.0 12683.0 11569.8 11781.0 12063.5 12212.3 12551.2 9414.9 9760.4 9914.6 10333.3 10341.8 9489.7 9891.4 9894.4 9967.9 9977.0 22:24:40 INFO - PROCESS | 21271 | -------- Summary: end -------- 22:24:40 INFO - PROCESS | 21271 | 22:24:40 INFO - TEST-INFO | 21271: exit 0 22:24:40 INFO - TEST-OK | dromaeo_css | took 1117676ms 22:24:40 INFO - TEST-START | kraken 22:24:40 INFO - operating with platform_type : linux_ 22:24:40 INFO - Initialising browser for kraken test... 22:24:40 INFO - TEST-INFO | started process 21448 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmqbq_O/profile http://localhost:56410/getInfo.html) 22:24:44 INFO - PROCESS | 21448 | __metrics Screen width/height:1600/1200 22:24:44 INFO - PROCESS | 21448 | colorDepth:24 22:24:44 INFO - PROCESS | 21448 | Browser inner width/height: 1024/697 22:24:44 INFO - PROCESS | 21448 | __metrics 22:24:45 ERROR - PROCESS | 21448 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:24:46 INFO - TEST-INFO | 21448: exit 0 22:24:46 INFO - Browser initialized. 22:24:46 INFO - Running cycle 1/1 for kraken test... 22:24:46 INFO - TEST-INFO | started process 21529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmqbq_O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:58 INFO - PROCESS | 21529 | RSS: Main: 157802496 22:24:58 INFO - PROCESS | 21529 | 22:25:00 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56410/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:01 INFO - PROCESS | 21529 | RSS: Main: 215699456 22:25:01 INFO - PROCESS | 21529 | 22:25:04 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56410/tests/kraken/driver.html?testName=audio-dft) 22:25:05 INFO - PROCESS | 21529 | RSS: Main: 202129408 22:25:05 INFO - PROCESS | 21529 | 22:25:08 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56410/tests/kraken/driver.html?testName=audio-fft) 22:25:09 INFO - PROCESS | 21529 | RSS: Main: 270295040 22:25:09 INFO - PROCESS | 21529 | 22:25:11 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56410/tests/kraken/driver.html?testName=audio-oscillator) 22:25:11 INFO - PROCESS | 21529 | RSS: Main: 180228096 22:25:11 INFO - PROCESS | 21529 | 22:25:14 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56410/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:25:14 INFO - PROCESS | 21529 | RSS: Main: 183906304 22:25:14 INFO - PROCESS | 21529 | 22:25:17 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56410/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:18 INFO - PROCESS | 21529 | RSS: Main: 220028928 22:25:18 INFO - PROCESS | 21529 | 22:25:21 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56410/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:22 INFO - PROCESS | 21529 | RSS: Main: 216518656 22:25:22 INFO - PROCESS | 21529 | 22:25:25 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56410/tests/kraken/driver.html?testName=json-parse-financial) 22:25:26 INFO - PROCESS | 21529 | RSS: Main: 219254784 22:25:26 INFO - PROCESS | 21529 | 22:25:27 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56410/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:25:28 INFO - PROCESS | 21529 | RSS: Main: 206888960 22:25:28 INFO - PROCESS | 21529 | 22:25:30 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:25:30 INFO - PROCESS | 21529 | RSS: Main: 208535552 22:25:30 INFO - PROCESS | 21529 | 22:25:33 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:25:33 INFO - PROCESS | 21529 | RSS: Main: 217161728 22:25:33 INFO - PROCESS | 21529 | 22:25:36 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:25:37 INFO - PROCESS | 21529 | RSS: Main: 224137216 22:25:37 INFO - PROCESS | 21529 | 22:25:40 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:25:40 INFO - PROCESS | 21529 | RSS: Main: 223473664 22:25:40 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | Cycle 1(1): loaded http://localhost:56410/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56410/tests/kraken/driver.html?testName=ai-astar) 22:25:42 INFO - PROCESS | 21529 | RSS: Main: 265715712 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | __start_tp_report 22:25:42 INFO - PROCESS | 21529 | _x_x_mozilla_page_load 22:25:42 INFO - PROCESS | 21529 | _x_x_mozilla_page_load_details 22:25:42 INFO - PROCESS | 21529 | |i|pagename|runs| 22:25:42 INFO - PROCESS | 21529 | |0;ai-astar;113;110;114;114;114;114;115;115;116;115 22:25:42 INFO - PROCESS | 21529 | |1;audio-beat-detection;165;130;131;196;131;139;158;131;184;133 22:25:42 INFO - PROCESS | 21529 | |2;audio-dft;162;162;159;163;164;161;163;163;161;160 22:25:42 INFO - PROCESS | 21529 | |3;audio-fft;75;76;75;125;75;76;79;76;104;75 22:25:42 INFO - PROCESS | 21529 | |4;audio-oscillator;84;84;116;85;86;84;114;84;86;126 22:25:42 INFO - PROCESS | 21529 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 22:25:42 INFO - PROCESS | 21529 | |6;imaging-darkroom;147;147;147;147;147;147;146;146;147;145 22:25:42 INFO - PROCESS | 21529 | |7;imaging-desaturate;95;95;94;94;94;95;94;95;94;95 22:25:42 INFO - PROCESS | 21529 | |8;json-parse-financial;65;65;65;66;66;66;64;65;65;66 22:25:42 INFO - PROCESS | 21529 | |9;json-stringify-tinderbox;66;65;65;66;65;64;66;65;66;65 22:25:42 INFO - PROCESS | 21529 | |10;stanford-crypto-aes;84;87;86;89;92;87;90;88;138;84 22:25:42 INFO - PROCESS | 21529 | |11;stanford-crypto-ccm;143;141;131;131;135;130;131;131;148;136 22:25:42 INFO - PROCESS | 21529 | |12;stanford-crypto-pbkdf2;179;174;178;173;174;174;173;173;175;170 22:25:42 INFO - PROCESS | 21529 | |13;stanford-crypto-sha256-iterative;67;68;67;67;67;67;67;66;66;65 22:25:42 INFO - PROCESS | 21529 | __end_tp_report 22:25:42 INFO - PROCESS | 21529 | __start_cc_report 22:25:42 INFO - PROCESS | 21529 | _x_x_mozilla_cycle_collect,1919 22:25:42 INFO - PROCESS | 21529 | __end_cc_report 22:25:42 INFO - PROCESS | 21529 | __startTimestamp1464931542761__endTimestamp 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | ------- Summary: start ------- 22:25:42 INFO - PROCESS | 21529 | Number of tests: 14 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:1.63 (1.4%) stddev-sans-first:1.69 22:25:42 INFO - PROCESS | 21529 | Values: 113.0 110.0 114.0 114.0 114.0 114.0 115.0 115.0 116.0 115.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:148.50 stddev:24.62 (16.6%) stddev-sans-first:25.49 22:25:42 INFO - PROCESS | 21529 | Values: 165.0 130.0 131.0 196.0 131.0 139.0 158.0 131.0 184.0 133.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.50 stddev:1.55 (1.0%) stddev-sans-first:1.64 22:25:42 INFO - PROCESS | 21529 | Values: 162.0 162.0 159.0 163.0 164.0 161.0 163.0 163.0 161.0 160.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#3] audio-fft Cycles:10 Average:83.60 Median:75.50 stddev:17.06 (22.6%) stddev-sans-first:17.81 22:25:42 INFO - PROCESS | 21529 | Values: 75.0 76.0 75.0 125.0 75.0 76.0 79.0 76.0 104.0 75.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:16.70 (19.9%) stddev-sans-first:17.24 22:25:42 INFO - PROCESS | 21529 | Values: 84.0 84.0 116.0 85.0 86.0 84.0 114.0 84.0 86.0 126.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 22:25:42 INFO - PROCESS | 21529 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 22:25:42 INFO - PROCESS | 21529 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 147.0 145.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:25:42 INFO - PROCESS | 21529 | Values: 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 95.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 22:25:42 INFO - PROCESS | 21529 | Values: 65.0 65.0 65.0 66.0 66.0 66.0 64.0 65.0 65.0 66.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 22:25:42 INFO - PROCESS | 21529 | Values: 66.0 65.0 65.0 66.0 65.0 64.0 66.0 65.0 66.0 65.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:87.50 stddev:16.18 (18.5%) stddev-sans-first:16.87 22:25:42 INFO - PROCESS | 21529 | Values: 84.0 87.0 86.0 89.0 92.0 87.0 90.0 88.0 138.0 84.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:135.50 stddev:6.27 (4.6%) stddev-sans-first:6.07 22:25:42 INFO - PROCESS | 21529 | Values: 143.0 141.0 131.0 131.0 135.0 130.0 131.0 131.0 148.0 136.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:174.00 stddev:2.58 (1.5%) stddev-sans-first:2.11 22:25:42 INFO - PROCESS | 21529 | Values: 179.0 174.0 178.0 173.0 174.0 174.0 173.0 173.0 175.0 170.0 22:25:42 INFO - PROCESS | 21529 | 22:25:42 INFO - PROCESS | 21529 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 22:25:42 INFO - PROCESS | 21529 | Values: 67.0 68.0 67.0 67.0 67.0 67.0 67.0 66.0 66.0 65.0 22:25:42 INFO - PROCESS | 21529 | -------- Summary: end -------- 22:25:42 INFO - PROCESS | 21529 | 22:25:43 INFO - TEST-INFO | 21529: exit 0 22:25:43 INFO - TEST-OK | kraken | took 62888ms 22:25:43 INFO - SUITE-END | took 1180s 22:25:43 INFO - Completed test suite (00:19:40) 22:25:43 INFO - javascript benchmark 22:25:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2673.9866300668496, 2681.785457163427, 2715.5519339309017, 2718.8368697878122, 2731.4537092581477, 4650.139724151026, 4672.742999135046, 4729.077829823186, 4785.090832741781, 4854.684445511044, 4694.765261736917, 4719.386479757635, 4721.244600863863, 4754.168020596399, 4785.736784476852, 4145.0, 4264.2111209426275, 4395.428594282739, 4421.57994990477, 4577.633789296865, 4396.032872768002, 4410.140022695576, 4423.270160423543, 4427.71219870709, 4443.666724995638, 4271.786410679453, 4310.137972405531, 4373.081652852873, 4408.515063343029, 4414.889627759299, 4272.722273052242, 4275.144971005811, 4275.5296917339065, 4310.762908040057, 4372.103718737651, 4305.440292761952, 4312.892177695551, 4324.762138082404, 4345.913081738379, 4372.956270437318, 4572.817087316504, 4590.265557510782, 4677.929831052537, 4692.178868697964, 4695.225287827504, 5121.743912804345, 5130.9230361544605, 5147.459516750725, 5147.6911385317, 5150.510701483353, 5143.562797162245, 5177.48225177483, 5196.090684130262, 5197.038547868657, 5247.2653828464045, 4614.400127983375, 4711.905761884812, 4751.95248047522, 4756.048790241965, 4779.091972525209, 5253.658512196695, 5283.260343551906, 5283.656562323436, 5286.97356513215, 5289.127293996447, 4851.951480485221, 5004.149294619923, 5006.974965125224, 5052.0, 5057.620678449131, 5047.772850221749, 5055.443901170868, 5057.797688092435, 5113.284140220372, 5138.4604616514725, 5070.619703522177, 5087.669301495465, 5088.618353623493, 5091.3381260436645, 5137.768800403991, 4909.214525675875, 4998.275250088789, 5054.949450505522, 5068.341115654941, 5070.416902056237, 4346.7609281489185, 4358.3244597087505, 4374.715643483163, 4426.070525189746, 4430.667699922455, 3793.070697679031, 3804.885853424402, 3813.504244448204, 3838.692904567628, 3846.6922646188236, 4297.05464797744, 4341.978290108529, 4420.049689316812, 4433.1577000369825, 4457.175422546774, 5056.949430505648, 5112.974435127875, 5118.0, 5124.846254612367, 5134.589232861362, 4426.44669416323, 4434.866953991385, 4435.179491794029, 4435.3125265583885, 4478.507364189936, 4457.732536047847, 4517.932231016537, 4649.163150632919, 4650.581447669726, 4687.1328804171335, 4750.334953106637, 4808.663393562417, 4813.278008298643, 4826.710397376169, 4841.95158048415, 5128.974355128201, 5129.076766181974, 5136.486351364833, 5139.794408223629, 5188.014277287303, 4689.531046895283, 4718.693284936468, 4775.546323099439, 4832.371791667061, 4884.65807393479, 4999.075171093359, 5035.244713293036, 5046.142155833444, 5054.2671312660195, 5099.26060721186, 5091.643584949166, 5095.15929871567, 5107.770150343169, 5110.540051395392, 5158.123119069842, 4815.927761083657, 4928.482509336612, 4933.457319694759, 4966.577840883565, 4999.250112483157, 5017.648764586444, 5039.042581909425, 5044.1424957756535, 5044.167712327425, 5066.797328106834, 4963.602911767122, 4964.106460837084, 4994.101061808764, 4999.850004499871, 5010.549050585465, 4864.683795553277, 4985.252212168204, 5037.0, 5053.014662140848, 5111.105556527518, 4933.654644174873, 4935.703857768545, 4947.233178857283, 4954.975225123995, 4998.975005124951], "value": 4686.18882650056, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.2122713110172, 600.4175949329152, 602.0367412140573, 605.2434456928839, 620.515997521933, 28003.719962800365, 28457.573136402945, 30357.392852142944, 30369.0, 30379.54430683539, 30126.397472050547, 30777.0, 41412.17175656485, 42053.36919946203, 42091.57908420915, 7625.199354067812, 7822.452428330019, 7840.333571646417, 7844.72543460979, 7856.450048496579, 7682.807929801772, 7802.102758182825, 7804.336631386339, 7809.531428114331, 7812.765617031499, 7802.921970780305, 7854.371650267994, 7856.72501462449, 7858.607069646495, 7865.68537258509, 7767.650455729506, 7794.025746781653, 7804.531728096333, 7809.804754881116, 7815.335696465807, 7712.190220026929, 7791.259830316111, 7804.882926756103, 7811.375089992844, 7811.687532498693, 37559.436608450895, 37952.620473795185, 37959.81020094882, 38039.23921521582, 38054.61945380566, 2972.5838382626234, 3062.5865508156335, 3099.1942095055224, 3313.4035873542753, 3316.718078963291, 28704.0, 29051.56422653662, 29744.70255297441, 29993.550096748568, 30148.8492557538, 3366.410878096348, 3402.8468718907707, 3406.914827129317, 3428.9828550857337, 3451.8446669899913, 264023.0, 270433.0, 270665.0, 271211.28788712254, 271341.0, 56555.71722141363, 57532.42467575271, 57557.0, 57579.712101439225, 57595.0, 33842.76965379328, 34977.825110874284, 34999.30001399958, 36728.63271367252, 36821.0, 15693.136877471848, 15829.129397883227, 15894.205289735466, 15900.52298431049, 15901.920490397706, 10950.835737463944, 10981.780364392667, 10988.285761425483, 10991.340519568852, 11011.559537618567, 18687.0, 18719.906400468182, 18737.1568279425, 18754.906225468785, 18796.718049229272, 6931.445484361239, 6956.373926346552, 7072.3634872861685, 7175.497715159889, 7244.97623862994, 844.3751623798345, 886.9246114080375, 921.3366376209119, 931.9487428191514, 1027.941220542842, 27334.726652733614, 28485.857570712014, 28534.714652853207, 28850.27874303159, 29044.0, 992.2359782967075, 1000.6597756762735, 1010.5338688299058, 1038.1694644284541, 1046.1839764983324, 14830.0, 15091.320890559951, 15114.622134446727, 15229.695406091816, 15359.692806144038, 398559.0, 402820.0, 403763.0, 403784.0, 404533.569310915, 51173.0, 51435.228471572955, 51825.0, 52005.479945200066, 52128.0, 1116.0569318925495, 1155.5608868629868, 1219.8353222314875, 1229.7601967616229, 1240.975180496385, 28346.0, 28580.0, 30475.0, 30818.84590577033, 30933.690663093083, 43435.78282108569, 44020.33969490396, 44061.33907991447, 44320.55679443164, 44389.11221775611, 354358.0, 361161.0, 361878.0, 362620.0, 362627.0, 34925.82537087298, 39424.59313915137, 39971.80014100027, 40472.0, 40667.1866562673, 43562.782186088865, 43773.78113109541, 43892.0, 44074.11851762883, 44530.105543139594, 37211.441828373136, 37496.6250337504, 37772.81113594415, 37878.2424351517, 38013.42979855249, 35694.64305356913, 36110.09724756902, 36125.45811812877, 36479.0, 36537.634623654485, 31681.841590791897, 31864.36271274608, 35533.64466355303, 37150.44274335833, 37485.81257093697, 31577.52633710542, 33782.263764048206, 33936.32127357488, 34194.14514637154, 34401.0, 25524.23427297184, 25635.615465767656, 26013.869930650224, 26107.0, 26287.73712262853], "value": 17776.20174969197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.7780992499537, 370.96475834795694, 377.86963497593337, 380.38757600263585, 392.81930312056454, 15889.2849821758, 17018.489445316645, 17040.829591704078, 17196.140192990348, 17466.301347946097, 16104.677906441864, 16486.25811838467, 17180.484585462425, 17196.82803171968, 17199.914000430013, 4872.07430588188, 4967.354243948282, 5055.368078990126, 5056.544910958013, 5057.620678449112, 3671.3575124353133, 3827.3876179811236, 4984.725840078794, 5414.810481633143, 5699.772009119651, 5713.542916566686, 5715.485606295432, 5739.684317362543, 5744.31068271808, 5749.626274292178, 5573.08044172711, 5640.520555752766, 5707.1724599933195, 5728.79949201778, 5755.712214389264, 3344.6320904700465, 4793.49668284832, 5040.697558146487, 5094.414142373637, 5097.082525145473, 19854.602907941906, 20292.492687682927, 20367.898160509252, 20655.17379304826, 20687.79312206874, 1806.9367572135043, 1817.7546031285872, 1936.9806301937083, 2094.853360264782, 2324.2097686786406, 6380.234371875358, 6403.8399040023905, 12846.293453860035, 12950.805737913939, 13056.738865222642, 1668.883178177528, 1721.208244207666, 1749.3439960014994, 1750.9212085456059, 1751.2382113780545, 65456.67271663659, 65643.0, 66045.0, 67204.6639766803, 67577.3242267581, 9417.90582094184, 9521.809563808685, 9719.271054670928, 9950.502474876228, 10046.876093192379, 13260.668483287995, 13372.398242079129, 13382.46470141203, 13404.061715679809, 13408.932955335162, 7589.658465369072, 7614.390848732088, 7656.655450504741, 7872.0, 7873.724419645399, 7599.4680372373405, 7607.505512141693, 7628.275313845176, 7723.189065148191, 7754.030746156731, 6142.508599312045, 6271.843203919847, 6348.841278968062, 6386.265579458329, 6488.967555162194, 1567.2242240091227, 1646.3167785369178, 1708.265445858268, 1734.3322820713863, 1742.8605711543046, 795.5266616363255, 856.9775330220674, 867.5402036920442, 914.794171311463, 949.8527728202001, 16953.0, 17009.82371147898, 17489.30042798299, 17598.0, 17801.46595602134, 869.4348673362365, 920.8940971788196, 925.2459245714721, 933.2953620016843, 965.8792650918635, 9771.97394273591, 10181.33821301613, 10194.133498652549, 10270.281080324306, 10339.586416543405, 329635.0, 329828.0, 330368.0, 343483.0, 348764.0, 64230.0, 65751.34248657548, 68087.31912680909, 68912.3108768906, 69827.65086174537, 903.6069309850104, 952.1050212799946, 960.6301573893961, 965.9178969787506, 971.0823272008026, 15644.608884777972, 15649.0, 15837.287322070764, 15926.203689815231, 16089.075838178895, 27495.587566186125, 33305.83347083346, 34065.65934340724, 35047.299054019284, 35051.82474087613, 45243.77378113088, 46418.84225311312, 48730.0, 49113.0, 49567.25649115194, 6172.753089876354, 7592.354649854824, 7815.179406162271, 7835.529868207926, 7953.5227886327, 8764.824703506021, 9318.254539636948, 10943.398113103856, 11328.660140195807, 11639.941800290944, 9694.5152742364, 9985.950070249603, 9986.800263994533, 10152.187824973837, 10432.113270372103, 10154.949225253826, 10213.33613315132, 10391.0, 10545.10366618846, 10762.892371076188, 10408.531616077446, 10503.737406564896, 10552.947235263775, 10635.255532112988, 10941.28881622692, 6773.322667733188, 7026.824329391806, 7129.572225666477, 7743.186965368555, 7887.211278872067, 9267.53662316895, 9400.247980161708, 9490.762730931505, 9708.805823883624, 9717.174040206379], "value": 8174.905407928525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.710940038491, 1429.0925262458338, 1441.4738620403552, 1556.2141118735044, 1753.5090174751067, 6471.644059576727, 6934.30656934307, 7191.137063552368, 7206.60363679998, 7376.446766492508, 7226.132864056321, 7227.024351712517, 7273.527220730649, 7282.526635768671, 7286.854262914739, 2115.947101322468, 2139.3902737719754, 2146.2380854796556, 2146.8497205195645, 2148.2373757316163, 2056.064490656755, 2058.259026750369, 2070.4202823209525, 2122.182959560565, 2181.7490988536365, 2144.5603651251454, 2161.778595093775, 2166.80498755112, 2170.8046275835172, 2189.343197040889, 2114.9259775907844, 2120.7561130469885, 2122.9256976005768, 2124.4370241885913, 2130.4141361125658, 2051.200031987511, 2114.3339847948, 2117.026167962739, 2117.7670456249953, 2120.5016821047043, 7175.533590316612, 7179.389751871045, 7222.133343998754, 7227.060482137288, 7234.2765723427765, 4424.424824772791, 4557.680962332638, 4558.133954548625, 4562.406887104688, 4565.315202719586, 2805.5370863807448, 2885.350796070867, 2943.470175368433, 2943.5143201371748, 2943.7350638442645, 877.4209022045483, 887.5340446265698, 896.5024411451642, 914.1909410171979, 930.432436213909, 9700.369475984038, 9782.119609235204, 9805.215582753362, 9808.950955245178, 9810.65662701802, 3751.737378383487, 3852.1910398816003, 3910.1006768443417, 3914.8629797956933, 3921.254961557295, 7577.355924746458, 7646.541207527455, 7659.770206893802, 7659.885101723478, 7662.0422447194105, 4354.194474022316, 4354.216241076637, 4360.934585981213, 4364.869053928387, 4366.694331396771, 3601.1537288737127, 4031.737937034083, 4065.4918135233097, 4117.91764164721, 4199.97900010498, 3316.5688460499982, 3318.7013168814924, 3326.478046442893, 3420.863165473403, 3420.880269190566, 1030.4332617060586, 1049.506731836036, 1052.394872948058, 1058.5342449322427, 1062.86182796236, 664.5315052887703, 725.8874874394477, 773.6866569039548, 829.088003196479, 871.1288711288711, 4966.826161084345, 4975.502449754996, 4990.201567749143, 5022.146235140035, 5022.949770502249, 433.8828516300581, 439.13709560712715, 442.22831159626656, 446.6315289885857, 592.3129170162676, 3454.0, 3589.4436362363876, 3597.964020359815, 3663.780173189617, 3677.4483827425565, 8556.401051926305, 9003.909960900439, 9037.638494460149, 9086.136817002374, 9091.727248182566, 8932.910670893209, 8962.103789620985, 8979.326550508738, 8982.326325525612, 9001.54992250385, 6474.741010359627, 6980.965095174492, 7010.964945175241, 7064.894026589605, 7089.042979197742, 5261.89476210466, 5263.868403289948, 5339.7063161526485, 5403.567714582745, 5507.449255074461, 7970.0, 8184.386171037077, 8278.254957053894, 8403.159684031549, 8413.20074592937, 8423.115572865006, 8557.529335886586, 8604.78488037779, 8626.78433039154, 8627.784305392415, 5774.56690748204, 5910.438508341701, 6412.198475190601, 6533.934660653333, 6536.607803531803, 6355.52333574993, 6409.166808314889, 6883.346082122191, 6887.586744795329, 6986.790396288119, 6027.427394397525, 6374.968125159345, 6411.93588064132, 6537.215534135934, 6554.737810487527, 6371.776987805404, 6469.2560355557835, 6474.449671777952, 6548.508861835287, 6584.473242140713, 5887.470127688529, 5900.085486749561, 5941.613795103304, 5947.732352044255, 5952.76188952437, 4404.7357158570585, 4405.625521830552, 4485.215087359764, 4509.842155524541, 4530.796114174936, 4374.650027997816, 4435.977820110878, 4474.351219073151, 4500.437445319335, 4515.322701594787], "value": 3869.1707798592756, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.0928775440014, 302.4329382407985, 309.50942755732154, 310.102253974743, 312.02181162056945, 4738.407699037621, 4797.184478638631, 4812.831550895719, 4832.710037397758, 4839.46765855756, 4487.192305385038, 4597.034622729222, 4598.356230127785, 4598.44818621765, 4735.431748190222, 3598.8560457581666, 3661.279198776584, 3678.5953545110015, 3754.943675844851, 3811.6950643948417, 3761.8683346082894, 3771.679407250387, 3789.0, 3789.3747531657245, 3797.7721336719746, 3700.111973126444, 3767.5478942526934, 3775.8867233983024, 3788.753731007476, 3801.8479260829536, 3764.661180493769, 3785.1861849702173, 3791.5971090696617, 3793.4499497572697, 3805.562360328556, 3346.297277571713, 3355.748318876094, 3360.0423879194555, 3373.1567108222944, 3376.1728376547658, 4601.309803529464, 4616.407339467878, 4618.607418369409, 4620.260758278693, 4644.558766917137, 4181.038361176916, 4261.275583150841, 4305.117450922553, 4317.373980772802, 4345.69580129391, 4988.251762235694, 5006.424261209934, 5011.949880501221, 5028.296038554606, 5041.269015992661, 3647.1611529348397, 3665.560132784083, 3666.6699997000396, 3681.7606855554573, 3687.46531752897, 4855.368802055746, 5168.043911876277, 5195.584353251731, 5213.296205012312, 5219.295395121648, 3577.910552236163, 3582.731295152874, 3589.120665436985, 3593.2813437312643, 3596.2987217492337, 3597.6508809196553, 3615.258871931248, 3654.250878569887, 3655.1775850433974, 3661.4804855984466, 4217.536071032184, 4233.640140588023, 4239.42767726353, 4239.491261048633, 4244.660427165819, 4786.688865223749, 4923.458419573915, 4973.6269779765935, 5005.27423523587, 5075.3402057733, 1298.363801737142, 1307.320193499375, 1319.142557337719, 1330.9334533273297, 1332.9666758330925, 838.6707069295236, 903.8056817784208, 926.8887733472027, 938.7842743646994, 943.1605870775015, 4399.868003959887, 4412.477695195153, 4423.51341352451, 4428.335749637516, 4443.844465443694, 5043.949560504421, 5052.898942021213, 5086.92369614456, 5111.488851114858, 5123.846284611468, 4240.346264956669, 4311.76285304305, 4323.891902702458, 4365.716228445141, 4397.252467080604, 2687.951698837455, 2697.5414179589516, 2708.837469751821, 2717.8449159107695, 2725.727427257298, 4760.785764640599, 4786.0, 4798.688085274446, 4847.903041939212, 4852.878678033077, 4965.453800081988, 5139.535232458731, 5150.793968241303, 5189.481051894781, 5201.947980520222, 4762.976185119052, 4769.451513075971, 4779.569838714532, 4785.641076919245, 4786.712797232177, 5007.198848184273, 5027.3464449621315, 5061.443241243386, 5062.772175252049, 5083.186690129562, 4728.38530990969, 5021.196608542616, 5025.0, 5057.190849464068, 5069.898602028013, 4850.80596776125, 4869.756512174306, 4874.0511990245395, 4886.486918873467, 4900.607951363953, 3633.945490817587, 3657.341678497896, 3669.944950825793, 3670.5595328560744, 3680.55833299995, 5108.233764935289, 5153.227015947488, 5160.451864440728, 5248.0, 5263.789208532349, 5020.472850350807, 5021.0, 5024.648274620888, 5063.0, 5067.479756073089, 5004.2493625956395, 5012.624053195952, 5035.471275516018, 5047.2429135629945, 5073.543381095719, 4707.8587642370785, 4773.355596994361, 4956.182229932165, 4993.226049962262, 4995.47547507521, 4907.509249075207, 5032.220005899091, 5044.419891712353, 5095.566876815512, 5105.7191854448365, 4967.602591792575, 4978.228374601942, 4981.476944920876, 4993.925091123709, 5005.899882002413], "value": 3781.9811199695755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.7544982007197, 615.9260888693358, 628.8176428835637, 637.0889627832202, 721.9855602887942, 12244.938775306122, 13021.60935171944, 13064.738705225902, 13076.150050246728, 13078.21530708157, 11889.881101188985, 12881.098323117385, 13054.934725326384, 13072.0, 14360.640983975405, 803.9959800201005, 818.1327792539897, 820.0692214336741, 820.6225136437246, 823.6622984576325, 819.5820131732829, 820.5117954816905, 820.5445977482494, 822.7737372222626, 823.6170180865896, 874.9125087491263, 876.1457578860624, 877.0221203358242, 878.3895192840978, 878.4377998081233, 870.5864714260739, 870.5951732444412, 870.7648934787635, 871.3943809052711, 871.4945331707594, 636.7102968149527, 637.9202599675041, 638.0684201066449, 638.4923985431593, 638.5881106686162, 12530.74938501234, 12838.422270997828, 12865.099443038991, 12884.226946383247, 12939.094263401566, 13309.134906231064, 13378.86621133786, 13438.86561134386, 13656.93171534136, 13825.0, 9787.434010558347, 9937.552810123561, 9947.1544918682, 9960.95019524905, 10037.648682296158, 3299.96700032999, 3334.499825026241, 3342.88299909502, 3371.7471189660628, 3459.705924996353, 56594.71702641502, 56640.0, 56667.0, 56703.14945275824, 59914.70042649759, 10266.281360304856, 10378.325408848401, 10402.843957340647, 10459.424731639681, 10702.892971070345, 18939.0, 19198.424047278604, 19278.71081933772, 19533.413997580094, 19562.804371956383, 6782.321767823179, 6878.58728476293, 6879.89680154788, 6926.0, 7092.113485814273, 3676.17286110623, 3758.3986562149926, 3887.1059656279213, 3894.571597124314, 3905.9804700976883, 2849.1167738001286, 2985.7611391088662, 2989.088328059919, 2990.820550766961, 2992.8503574821607, 497.2069549069229, 497.659103514094, 508.1234869849554, 510.2983397827987, 511.92065229889425, 437.72860826287905, 562.0669688317373, 569.1349149293134, 574.9482546570829, 579.1880420558214, 8276.08963014068, 8277.710280140287, 8292.0, 8331.33349332052, 8553.700620478254, 532.8348212054276, 534.9946500534945, 535.5367607019978, 539.8326518779191, 548.9215131572455, 3994.141259629194, 4003.3594624859884, 4044.2315959967514, 4072.6538244249573, 4097.7541347519245, 64987.0, 66392.66803665951, 84211.84199343002, 90650.0, 93650.0, 48233.27650085252, 54050.0, 56833.7158314214, 62708.686456567426, 64824.67587662126, 66720.6663966677, 68312.3168768316, 68497.3150268491, 69113.0, 69645.65177174183, 8296.585170741559, 8341.95622072596, 8366.958165209378, 8709.782255443411, 8883.466991980502, 43131.353029703954, 43879.122417552106, 44351.556484436034, 44631.776841115585, 45263.094738104395, 61449.0, 61493.0, 61889.0, 61892.0, 62447.375526245974, 11615.548056492938, 11774.587889423829, 11782.17524773292, 11973.401329933295, 12036.157468977088, 12682.809757853454, 12733.172343797623, 12903.290319032169, 12959.611211663665, 13259.337033148111, 11671.35807530594, 11784.705382365166, 11928.642140735792, 11956.58151964677, 12297.938510307391, 11637.301761894314, 11966.880331196924, 12064.638060858188, 12173.0, 12683.0, 11569.768604627692, 11781.0, 12063.457144428347, 12212.32832194238, 12551.246925184518, 9414.914607403889, 9760.365576237522, 9914.553845076845, 10333.328333658288, 10341.793164136825, 9489.66786162481, 9891.35706179096, 9894.356866803635, 9967.850482242628, 9977.0], "value": 6494.195215371451, "unit": "score"}], "name": "dromaeo_css", "value": 6336.403657692184, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 116.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 130.0, 131.0, 196.0, 131.0, 139.0, 158.0, 131.0, 184.0, 133.0], "value": 149.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 159.0, 163.0, 164.0, 161.0, 163.0, 163.0, 161.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 125.0, 75.0, 76.0, 79.0, 76.0, 104.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 85.0, 86.0, 84.0, 114.0, 84.0, 86.0, 126.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 145.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 64.0, 65.0, 65.0, 66.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 65.0, 64.0, 66.0, 65.0, 66.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 89.0, 92.0, 87.0, 90.0, 88.0, 138.0, 84.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 131.0, 131.0, 135.0, 130.0, 131.0, 131.0, 148.0, 136.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 174.0, 178.0, 173.0, 174.0, 174.0, 173.0, 173.0, 175.0, 170.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1562.0, "alertThreshold": 2.0}]} 22:25:44 INFO - Return code: 0 22:25:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:25:44 ERROR - # TBPL SUCCESS # 22:25:44 INFO - Running post-action listener: _resource_record_post_action 22:25:44 INFO - ##### 22:25:44 INFO - ##### Finished run-tests step (success) 22:25:44 INFO - ##### 22:25:44 INFO - Running post-run listener: _resource_record_post_run 22:25:44 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 12288; Write bytes: 468852736; Read time: 232; Write time: 308584 22:25:44 INFO - TinderboxPrint: CPU usage
13.1% 22:25:44 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 232 22:25:44 INFO - TinderboxPrint: I/O write bytes / time
468,852,736 / 308,584 22:25:44 INFO - TinderboxPrint: CPU idle
8,311.5 (86.9%) 22:25:44 INFO - TinderboxPrint: CPU user
1,211.1 (12.7%) 22:25:44 INFO - TinderboxPrint: Swap in / out
0 / 0 22:25:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 188100608; Read time: 64; Write time: 131784 22:25:44 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 8192; Write bytes: 280752128; Read time: 168; Write time: 176800 22:25:44 INFO - Running post-run listener: _upload_blobber_files 22:25:44 INFO - Blob upload gear active. 22:25:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:25:44 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 22:25:44 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:25:44 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:25:45 INFO - (blobuploader) - INFO - Open directory for files ... 22:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:25:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:45 INFO - (blobuploader) - INFO - Done attempting. 22:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:50 INFO - (blobuploader) - INFO - Done attempting. 22:25:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:25:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:25:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:51 INFO - (blobuploader) - INFO - Done attempting. 22:25:51 INFO - (blobuploader) - INFO - Iteration through files over. 22:25:51 INFO - Return code: 0 22:25:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:25:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:25:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/222396055a0b0906accf2a7a00d79a2bf2bf42735bdf70cb1dd30d1c82fb3bd5c7f5de6970b65cfadb9afc1575cdbb980bb9d1ed4cba2733f409d54d690f01d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6a7e143f5963c26bb08c69078b9e222463f4cd6601140da86f8651b89f36e06da2a41ba091567276a2caf863245e95d55807f07dd65efcf616d3e6f69929c74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb95586e0c1564035e34adf94bf02cf2ed80f982185370b57b3d38c7c61711eed8d2cabdb41ca73b4eb4ccb1f7a2b105aaafba833e93f5e3a7f5b0373e991f9b"} 22:25:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:25:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:25:51 INFO - Contents: 22:25:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/222396055a0b0906accf2a7a00d79a2bf2bf42735bdf70cb1dd30d1c82fb3bd5c7f5de6970b65cfadb9afc1575cdbb980bb9d1ed4cba2733f409d54d690f01d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6a7e143f5963c26bb08c69078b9e222463f4cd6601140da86f8651b89f36e06da2a41ba091567276a2caf863245e95d55807f07dd65efcf616d3e6f69929c74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb95586e0c1564035e34adf94bf02cf2ed80f982185370b57b3d38c7c61711eed8d2cabdb41ca73b4eb4ccb1f7a2b105aaafba833e93f5e3a7f5b0373e991f9b"} 22:25:51 INFO - Running post-run listener: copy_logs_to_upload_dir 22:25:51 INFO - Copying logs to upload dir... 22:25:51 INFO - mkdir: /builds/slave/test/build/upload/logs 22:25:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.157470 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-02 22:25:50.906650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:25:50.910093) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/222396055a0b0906accf2a7a00d79a2bf2bf42735bdf70cb1dd30d1c82fb3bd5c7f5de6970b65cfadb9afc1575cdbb980bb9d1ed4cba2733f409d54d690f01d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6a7e143f5963c26bb08c69078b9e222463f4cd6601140da86f8651b89f36e06da2a41ba091567276a2caf863245e95d55807f07dd65efcf616d3e6f69929c74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb95586e0c1564035e34adf94bf02cf2ed80f982185370b57b3d38c7c61711eed8d2cabdb41ca73b4eb4ccb1f7a2b105aaafba833e93f5e3a7f5b0373e991f9b"} build_url:https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/FTfy4IxPTEOrF16sOddK-g/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/Firefox-Non-PGO/sha512/222396055a0b0906accf2a7a00d79a2bf2bf42735bdf70cb1dd30d1c82fb3bd5c7f5de6970b65cfadb9afc1575cdbb980bb9d1ed4cba2733f409d54d690f01d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6a7e143f5963c26bb08c69078b9e222463f4cd6601140da86f8651b89f36e06da2a41ba091567276a2caf863245e95d55807f07dd65efcf616d3e6f69929c74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb95586e0c1564035e34adf94bf02cf2ed80f982185370b57b3d38c7c61711eed8d2cabdb41ca73b4eb4ccb1f7a2b105aaafba833e93f5e3a7f5b0373e991f9b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:25:50.939217) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:25:50.939551) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1464913083.289331-143621742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:25:51.024533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:25:51.024853) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:25:51.025172) ========= ========= Total master_lag: 0.17 =========