builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1463206267.66 results: success (0) buildid: 20160513203052 builduid: a7ce0406212944efbb072d3e148d0dde revision: 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:07.664777) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:07.665258) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:07.665549) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:07.681565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:07.681829) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 23:11:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2016-05-13 23:11:08 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124665 ========= master_lag: 0.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:08.162066) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:08.162340) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022757 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:08.196967) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 23:11:08.197326) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 --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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 23:11:08,881 truncating revision to first 12 chars 2016-05-13 23:11:08,881 Setting DEBUG logging. 2016-05-13 23:11:08,881 attempt 1/10 2016-05-13 23:11:08,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/44dc9457053b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 23:11:09,912 unpacking tar archive at: fx-team-44dc9457053b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.324734 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 23:11:09.534891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:09.535226) ========= script_repo_revision: 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:09.535677) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:09.535958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 23:11:09.542818) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-13 23:11:09.543095) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:11:10 INFO - MultiFileLogger online at 20160513 23:11:10 in /builds/slave/test 23:11:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:11:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:11:10 INFO - {'append_to_log': False, 23:11:10 INFO - 'base_work_dir': '/builds/slave/test', 23:11:10 INFO - 'blob_upload_branch': 'Fx-Team', 23:11:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:11:10 INFO - 'branch': 'Fx-Team', 23:11:10 INFO - 'buildbot_json_path': 'buildprops.json', 23:11:10 INFO - 'config_files': ('talos/linux_config.py',), 23:11:10 INFO - 'default_actions': ('clobber', 23:11:10 INFO - 'read-buildbot-config', 23:11:10 INFO - 'download-and-extract', 23:11:10 INFO - 'populate-webroot', 23:11:10 INFO - 'create-virtualenv', 23:11:10 INFO - 'install', 23:11:10 INFO - 'run-tests'), 23:11:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:11:10 INFO - 'download_minidump_stackwalk': True, 23:11:10 INFO - 'download_symbols': 'ondemand', 23:11:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:11:10 INFO - 'tooltool.py': '/tools/tooltool.py', 23:11:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:11:10 INFO - '/tools/misc-python/virtualenv.py')}, 23:11:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:11:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:11:10 INFO - 'installer_path': 'installer.exe', 23:11:10 INFO - 'log_level': 'info', 23:11:10 INFO - 'log_name': 'talos', 23:11:10 INFO - 'log_to_console': True, 23:11:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:11:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:11:10 INFO - 'opt_config_files': (), 23:11:10 INFO - 'pip_index': False, 23:11:10 INFO - 'sps_profile': False, 23:11:10 INFO - 'sps_profile_interval': 0, 23:11:10 INFO - 'suite': 'dromaeojs', 23:11:10 INFO - 'system_bits': '32', 23:11:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:11:10 INFO - 'title': 'talos-linux64-ix-061', 23:11:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:11:10 INFO - 'use_talos_json': True, 23:11:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:11:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:11:10 INFO - 'work_dir': 'build'} 23:11:10 INFO - ##### 23:11:10 INFO - ##### Running clobber step. 23:11:10 INFO - ##### 23:11:10 INFO - Running pre-action listener: _resource_record_pre_action 23:11:10 INFO - Running main action method: clobber 23:11:10 INFO - rmtree: /builds/slave/test/build 23:11:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:11:10 INFO - Running post-action listener: _resource_record_post_action 23:11:10 INFO - ##### 23:11:10 INFO - ##### Finished clobber step (success) 23:11:10 INFO - ##### 23:11:10 INFO - ##### 23:11:10 INFO - ##### Running read-buildbot-config step. 23:11:10 INFO - ##### 23:11:10 INFO - Running pre-action listener: _resource_record_pre_action 23:11:10 INFO - Running main action method: read_buildbot_config 23:11:10 INFO - Using buildbot properties: 23:11:10 INFO - { 23:11:10 INFO - "project": "", 23:11:10 INFO - "product": "firefox", 23:11:10 INFO - "script_repo_revision": "production", 23:11:10 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:11:10 INFO - "repository": "", 23:11:10 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:11:10 INFO - "buildid": "20160513203052", 23:11:10 INFO - "pgo_build": "True", 23:11:10 INFO - "basedir": "/builds/slave/test", 23:11:10 INFO - "buildnumber": 86, 23:11:10 INFO - "slavename": "talos-linux64-ix-061", 23:11:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:11:10 INFO - "platform": "ubuntu64_hw", 23:11:10 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:11:10 INFO - "branch": "fx-team", 23:11:10 INFO - "repo_path": "integration/fx-team", 23:11:10 INFO - "revision": "44dc9457053b0f6ecacbc01c7d11a8b457c3cb55", 23:11:10 INFO - "stage_platform": "linux64-pgo", 23:11:10 INFO - "builduid": "a7ce0406212944efbb072d3e148d0dde", 23:11:10 INFO - "slavebuilddir": "test" 23:11:10 INFO - } 23:11:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:11:10 INFO - Running post-action listener: _resource_record_post_action 23:11:10 INFO - ##### 23:11:10 INFO - ##### Finished read-buildbot-config step (success) 23:11:10 INFO - ##### 23:11:10 INFO - ##### 23:11:10 INFO - ##### Running download-and-extract step. 23:11:10 INFO - ##### 23:11:10 INFO - Running pre-action listener: _resource_record_pre_action 23:11:10 INFO - Running main action method: download_and_extract 23:11:10 INFO - mkdir: /builds/slave/test/build/tests 23:11:10 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')]} 23:11:10 INFO - https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:11:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:11:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:11:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:11 INFO - Downloaded 1361 bytes. 23:11:11 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:11:11 INFO - Using the following test package requirements: 23:11:11 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:11:11 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:11:11 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:11:11 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'jsshell-linux-x86_64.zip'], 23:11:11 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:11:11 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:11:11 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:11:11 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:11:11 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:11:11 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:11 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:11:11 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:11 INFO - https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:11:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:11:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:11:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:13 INFO - Downloaded 24022480 bytes. 23:11:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:11:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:11:14 INFO - Return code: 0 23:11:14 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 23:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:14 INFO - https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:11:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:11:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:11:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:16 INFO - Downloaded 24022480 bytes. 23:11:16 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'] 23:11:16 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 23:11:18 INFO - Return code: 0 23:11: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')]} 23:11:18 INFO - https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:11:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:11:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:11:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:19 INFO - Downloaded 10840194 bytes. 23:11:19 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'] 23:11:19 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 23:11:19 INFO - Return code: 0 23:11:19 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')]} 23:11:19 INFO - https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:11:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:11:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:11:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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 23:11:20 INFO - Downloaded 58395874 bytes. 23:11:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:11:20 INFO - mkdir: /builds/slave/test/properties 23:11:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:11:20 INFO - Writing to file /builds/slave/test/properties/build_url 23:11:20 INFO - Contents: 23:11:20 INFO - build_url:https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:11:21 INFO - Running post-action listener: _resource_record_post_action 23:11:21 INFO - Running post-action listener: set_extra_try_arguments 23:11:21 INFO - ##### 23:11:21 INFO - ##### Finished download-and-extract step (success) 23:11:21 INFO - ##### 23:11:21 INFO - ##### 23:11:21 INFO - ##### Running populate-webroot step. 23:11:21 INFO - ##### 23:11:21 INFO - Running pre-action listener: _resource_record_pre_action 23:11:21 INFO - Running main action method: populate_webroot 23:11:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:11:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:11:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:11:21 INFO - '--tppagecycles', 23:11:21 INFO - '7'], 23:11:21 INFO - 'tests': ['tsvgm']}}, 23:11:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:11:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:11:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:11:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:11:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['damp', 'tps']}, 23:11:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'tests': ['damp', 'tps']}, 23:11:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['dromaeo_dom']}, 23:11:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:11:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'other-e10s': {'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['a11yr', 23:11:21 INFO - 'ts_paint', 23:11:21 INFO - 'tpaint', 23:11:21 INFO - 'sessionrestore', 23:11:21 INFO - 'sessionrestore_no_auto_restore', 23:11:21 INFO - 'tabpaint']}, 23:11:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['tsvgx', 23:11:21 INFO - 'tsvgr_opacity', 23:11:21 INFO - 'tart', 23:11:21 INFO - 'tscrollx', 23:11:21 INFO - 'cart']}, 23:11:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:11:21 INFO - 'tsvgr_opacity', 23:11:21 INFO - 'tart', 23:11:21 INFO - 'tscrollx', 23:11:21 INFO - 'cart']}, 23:11:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'talos_options': ['--disable-e10s'], 23:11:21 INFO - 'tests': ['tp5o']}, 23:11:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'tests': ['tp5o']}, 23:11:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'talos_options': ['--disable-e10s', 23:11:21 INFO - '--xperf_path', 23:11:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:21 INFO - 'tests': ['tp5n']}, 23:11:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:21 INFO - 'talos_options': ['--xperf_path', 23:11:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:21 INFO - 'tests': ['tp5n']}}, 23:11:21 INFO - 'talos.zip': {'path': '', 23:11:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:11:21 INFO - Running post-action listener: _resource_record_post_action 23:11:21 INFO - ##### 23:11:21 INFO - ##### Finished populate-webroot step (success) 23:11:21 INFO - ##### 23:11:21 INFO - ##### 23:11:21 INFO - ##### Running create-virtualenv step. 23:11:21 INFO - ##### 23:11:21 INFO - Running pre-action listener: _resource_record_pre_action 23:11:21 INFO - Running main action method: create_virtualenv 23:11:21 INFO - Creating virtualenv /builds/slave/test/build/venv 23:11: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 23:11:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:11:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:11:21 INFO - Using real prefix '/usr' 23:11:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:11:21 INFO - Installing distribute.............................................................................................................................................................................................done. 23:11:23 INFO - Installing pip.................done. 23:11:23 INFO - Return code: 0 23:11:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:11:23 INFO - Using env: {'DISPLAY': ':0', 23:11:23 INFO - 'HOME': '/home/cltbld', 23:11:23 INFO - 'LANG': 'en_US.UTF-8', 23:11:23 INFO - 'LANGUAGE': 'en_US:en', 23:11:23 INFO - 'LOGNAME': 'cltbld', 23:11:23 INFO - 'MAIL': '/var/mail/cltbld', 23:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:23 INFO - 'MOZ_NO_REMOTE': '1', 23:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:23 INFO - 'NO_EM_RESTART': '1', 23:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:23 INFO - 'PWD': '/builds/slave/test', 23:11:23 INFO - 'SHELL': '/bin/bash', 23:11:23 INFO - 'SHLVL': '1', 23:11:23 INFO - 'TERM': 'linux', 23:11:23 INFO - 'TMOUT': '86400', 23:11:23 INFO - 'USER': 'cltbld', 23:11:23 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:23 INFO - '_': '/tools/buildbot/bin/python'} 23:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11: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 23:11:24 INFO - Cleaning up... 23:11:24 INFO - Return code: 0 23:11:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11: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 23:11: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 23:11:24 INFO - Using env: {'DISPLAY': ':0', 23:11:24 INFO - 'HOME': '/home/cltbld', 23:11:24 INFO - 'LANG': 'en_US.UTF-8', 23:11:24 INFO - 'LANGUAGE': 'en_US:en', 23:11:24 INFO - 'LOGNAME': 'cltbld', 23:11:24 INFO - 'MAIL': '/var/mail/cltbld', 23:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:24 INFO - 'MOZ_NO_REMOTE': '1', 23:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:24 INFO - 'NO_EM_RESTART': '1', 23:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:24 INFO - 'PWD': '/builds/slave/test', 23:11:24 INFO - 'SHELL': '/bin/bash', 23:11:24 INFO - 'SHLVL': '1', 23:11:24 INFO - 'TERM': 'linux', 23:11:24 INFO - 'TMOUT': '86400', 23:11:24 INFO - 'USER': 'cltbld', 23:11:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:24 INFO - '_': '/tools/buildbot/bin/python'} 23:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:24 INFO - Downloading/unpacking psutil>=3.1.1 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:11:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:26 INFO - Installing collected packages: psutil 23:11:26 INFO - Running setup.py install for psutil 23:11:26 INFO - building 'psutil._psutil_linux' extension 23:11: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 23:11: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 23:11:26 INFO - building 'psutil._psutil_posix' extension 23:11: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 23:11: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 23:11:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:26 INFO - Successfully installed psutil 23:11:26 INFO - Cleaning up... 23:11:26 INFO - Return code: 0 23:11:26 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 23:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 23:11:26 INFO - Using env: {'DISPLAY': ':0', 23:11:26 INFO - 'HOME': '/home/cltbld', 23:11:26 INFO - 'LANG': 'en_US.UTF-8', 23:11:26 INFO - 'LANGUAGE': 'en_US:en', 23:11:26 INFO - 'LOGNAME': 'cltbld', 23:11:26 INFO - 'MAIL': '/var/mail/cltbld', 23:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:26 INFO - 'MOZ_NO_REMOTE': '1', 23:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:26 INFO - 'NO_EM_RESTART': '1', 23:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:26 INFO - 'PWD': '/builds/slave/test', 23:11:26 INFO - 'SHELL': '/bin/bash', 23:11:26 INFO - 'SHLVL': '1', 23:11:26 INFO - 'TERM': 'linux', 23:11:26 INFO - 'TMOUT': '86400', 23:11:26 INFO - 'USER': 'cltbld', 23:11:26 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:26 INFO - '_': '/tools/buildbot/bin/python'} 23:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:27 INFO - Downloading/unpacking mozsystemmonitor==0.1 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:28 INFO - Downloading mozsystemmonitor-0.1.tar.gz 23:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 23:11:29 INFO - Installing collected packages: mozsystemmonitor 23:11:29 INFO - Running setup.py install for mozsystemmonitor 23:11:29 INFO - Successfully installed mozsystemmonitor 23:11:29 INFO - Cleaning up... 23:11:29 INFO - Return code: 0 23:11:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11: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 23:11: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 23:11:29 INFO - Using env: {'DISPLAY': ':0', 23:11:29 INFO - 'HOME': '/home/cltbld', 23:11:29 INFO - 'LANG': 'en_US.UTF-8', 23:11:29 INFO - 'LANGUAGE': 'en_US:en', 23:11:29 INFO - 'LOGNAME': 'cltbld', 23:11:29 INFO - 'MAIL': '/var/mail/cltbld', 23:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:29 INFO - 'MOZ_NO_REMOTE': '1', 23:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:29 INFO - 'NO_EM_RESTART': '1', 23:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:29 INFO - 'PWD': '/builds/slave/test', 23:11:29 INFO - 'SHELL': '/bin/bash', 23:11:29 INFO - 'SHLVL': '1', 23:11:29 INFO - 'TERM': 'linux', 23:11:29 INFO - 'TMOUT': '86400', 23:11:29 INFO - 'USER': 'cltbld', 23:11:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:29 INFO - '_': '/tools/buildbot/bin/python'} 23:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:29 INFO - Downloading/unpacking blobuploader==1.2.4 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:31 INFO - Downloading blobuploader-1.2.4.tar.gz 23:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:11:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:11:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:31 INFO - Downloading docopt-0.6.1.tar.gz 23:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:11:32 INFO - Installing collected packages: blobuploader, requests, docopt 23:11:32 INFO - Running setup.py install for blobuploader 23:11:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:11:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:11:32 INFO - Running setup.py install for requests 23:11:32 INFO - Running setup.py install for docopt 23:11:32 INFO - Successfully installed blobuploader requests docopt 23:11:32 INFO - Cleaning up... 23:11:32 INFO - Return code: 0 23:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11: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 23:11: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 23:11:32 INFO - Using env: {'DISPLAY': ':0', 23:11:32 INFO - 'HOME': '/home/cltbld', 23:11:32 INFO - 'LANG': 'en_US.UTF-8', 23:11:32 INFO - 'LANGUAGE': 'en_US:en', 23:11:32 INFO - 'LOGNAME': 'cltbld', 23:11:32 INFO - 'MAIL': '/var/mail/cltbld', 23:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:32 INFO - 'MOZ_NO_REMOTE': '1', 23:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:32 INFO - 'NO_EM_RESTART': '1', 23:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:32 INFO - 'PWD': '/builds/slave/test', 23:11:32 INFO - 'SHELL': '/bin/bash', 23:11:32 INFO - 'SHLVL': '1', 23:11:32 INFO - 'TERM': 'linux', 23:11:32 INFO - 'TMOUT': '86400', 23:11:32 INFO - 'USER': 'cltbld', 23:11:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:32 INFO - '_': '/tools/buildbot/bin/python'} 23:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:32 INFO - Running setup.py (path:/tmp/pip-8kEOhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:32 INFO - Running setup.py (path:/tmp/pip-j15R7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:32 INFO - Running setup.py (path:/tmp/pip-CxGlVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:33 INFO - Running setup.py (path:/tmp/pip-n5Meis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:33 INFO - Running setup.py (path:/tmp/pip-P6nquk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:33 INFO - Running setup.py (path:/tmp/pip-KeR6em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:33 INFO - Running setup.py (path:/tmp/pip-WAimyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:33 INFO - Running setup.py (path:/tmp/pip-AXjfc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:33 INFO - Running setup.py (path:/tmp/pip-qOoc8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:33 INFO - Running setup.py (path:/tmp/pip-WqxIbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:33 INFO - Running setup.py (path:/tmp/pip-wNAXmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:33 INFO - Running setup.py (path:/tmp/pip-oTR8QW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:33 INFO - Running setup.py (path:/tmp/pip-WfzAEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:33 INFO - Running setup.py (path:/tmp/pip-FvtNSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:33 INFO - Running setup.py (path:/tmp/pip-xLad10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:33 INFO - Running setup.py (path:/tmp/pip-jk46xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:34 INFO - Running setup.py (path:/tmp/pip-IMiE2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:11:34 INFO - Running setup.py install for manifestparser 23:11:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Running setup.py install for mozcrash 23:11:34 INFO - Running setup.py install for mozdebug 23:11:34 INFO - Running setup.py install for mozdevice 23:11:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Running setup.py install for mozfile 23:11:34 INFO - Running setup.py install for mozhttpd 23:11:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Running setup.py install for mozinfo 23:11:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Running setup.py install for mozInstall 23:11:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:11:34 INFO - Running setup.py install for mozleak 23:11:34 INFO - Running setup.py install for mozlog 23:11:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Running setup.py install for moznetwork 23:11:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Running setup.py install for mozprocess 23:11:35 INFO - Running setup.py install for mozprofile 23:11:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Running setup.py install for mozrunner 23:11:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Running setup.py install for mozscreenshot 23:11:35 INFO - Running setup.py install for moztest 23:11:35 INFO - Running setup.py install for mozversion 23:11:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:11:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:11:35 INFO - Cleaning up... 23:11:35 INFO - Return code: 0 23:11:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:35 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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11:35 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 23:11:35 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 23:11:35 INFO - Using env: {'DISPLAY': ':0', 23:11:35 INFO - 'HOME': '/home/cltbld', 23:11:35 INFO - 'LANG': 'en_US.UTF-8', 23:11:35 INFO - 'LANGUAGE': 'en_US:en', 23:11:35 INFO - 'LOGNAME': 'cltbld', 23:11:35 INFO - 'MAIL': '/var/mail/cltbld', 23:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:35 INFO - 'MOZ_NO_REMOTE': '1', 23:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:35 INFO - 'NO_EM_RESTART': '1', 23:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:35 INFO - 'PWD': '/builds/slave/test', 23:11:35 INFO - 'SHELL': '/bin/bash', 23:11:35 INFO - 'SHLVL': '1', 23:11:35 INFO - 'TERM': 'linux', 23:11:35 INFO - 'TMOUT': '86400', 23:11:35 INFO - 'USER': 'cltbld', 23:11:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:35 INFO - '_': '/tools/buildbot/bin/python'} 23:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:36 INFO - Running setup.py (path:/tmp/pip-V_mPgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:36 INFO - Running setup.py (path:/tmp/pip-1BF0T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:36 INFO - Running setup.py (path:/tmp/pip-rcl4QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:36 INFO - Running setup.py (path:/tmp/pip-2FpLIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:36 INFO - Running setup.py (path:/tmp/pip-fmKQ38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:36 INFO - Running setup.py (path:/tmp/pip-PXqLCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:36 INFO - Running setup.py (path:/tmp/pip-eZEHqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:36 INFO - Running setup.py (path:/tmp/pip-rZPy34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:36 INFO - Running setup.py (path:/tmp/pip-jAvX0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11: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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:36 INFO - Running setup.py (path:/tmp/pip-pvPcOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:36 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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:36 INFO - Running setup.py (path:/tmp/pip-eu1BaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:36 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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:36 INFO - Running setup.py (path:/tmp/pip-v_uGaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:36 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)) 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:36 INFO - Running setup.py (path:/tmp/pip-ZZK5Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11: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)) 23:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:37 INFO - Running setup.py (path:/tmp/pip-VXmK3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11: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)) 23:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:37 INFO - Running setup.py (path:/tmp/pip-1zpV_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11: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)) 23:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:37 INFO - Running setup.py (path:/tmp/pip-4zR11u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11: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)) 23:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:37 INFO - Running setup.py (path:/tmp/pip-Jjadcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11: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)) 23:11: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)) 23:11: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)) 23:11: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)) 23:11: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)) 23:11: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)) 23:11:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:39 INFO - Downloading blessings-1.6.tar.gz 23:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:11:39 INFO - Installing collected packages: blessings 23:11:39 INFO - Running setup.py install for blessings 23:11:39 INFO - Successfully installed blessings 23:11:39 INFO - Cleaning up... 23:11:39 INFO - Return code: 0 23:11:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:11:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:39 INFO - Reading from file tmpfile_stdout 23:11:39 INFO - Current package versions: 23:11:39 INFO - argparse == 1.2.1 23:11:39 INFO - blessings == 1.6 23:11:39 INFO - blobuploader == 1.2.4 23:11:39 INFO - docopt == 0.6.1 23:11:39 INFO - manifestparser == 1.1 23:11:39 INFO - mozInstall == 1.12 23:11:39 INFO - mozcrash == 0.17 23:11:39 INFO - mozdebug == 0.1 23:11:39 INFO - mozdevice == 0.48 23:11:39 INFO - mozfile == 1.2 23:11:39 INFO - mozhttpd == 0.7 23:11:39 INFO - mozinfo == 0.9 23:11:39 INFO - mozleak == 0.1 23:11:39 INFO - mozlog == 3.2 23:11:39 INFO - moznetwork == 0.27 23:11:39 INFO - mozprocess == 0.22 23:11:39 INFO - mozprofile == 0.28 23:11:39 INFO - mozrunner == 6.11 23:11:39 INFO - mozscreenshot == 0.1 23:11:39 INFO - mozsystemmonitor == 0.1 23:11:39 INFO - moztest == 0.7 23:11:39 INFO - mozversion == 1.4 23:11:39 INFO - psutil == 3.1.1 23:11:39 INFO - requests == 1.2.3 23:11:39 INFO - wsgiref == 0.1.2 23:11:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11: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 23:11: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 23:11:39 INFO - Using env: {'DISPLAY': ':0', 23:11:39 INFO - 'HOME': '/home/cltbld', 23:11:39 INFO - 'LANG': 'en_US.UTF-8', 23:11:39 INFO - 'LANGUAGE': 'en_US:en', 23:11:39 INFO - 'LOGNAME': 'cltbld', 23:11:39 INFO - 'MAIL': '/var/mail/cltbld', 23:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:39 INFO - 'MOZ_NO_REMOTE': '1', 23:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:39 INFO - 'NO_EM_RESTART': '1', 23:11:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:39 INFO - 'PWD': '/builds/slave/test', 23:11:39 INFO - 'SHELL': '/bin/bash', 23:11:39 INFO - 'SHLVL': '1', 23:11:39 INFO - 'TERM': 'linux', 23:11:39 INFO - 'TMOUT': '86400', 23:11:39 INFO - 'USER': 'cltbld', 23:11:39 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:39 INFO - '_': '/tools/buildbot/bin/python'} 23:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 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)) 23:11:39 INFO - Cleaning up... 23:11:39 INFO - Return code: 0 23:11:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:11:39 INFO - Using env: {'DISPLAY': ':0', 23:11:39 INFO - 'HOME': '/home/cltbld', 23:11:39 INFO - 'LANG': 'en_US.UTF-8', 23:11:39 INFO - 'LANGUAGE': 'en_US:en', 23:11:39 INFO - 'LOGNAME': 'cltbld', 23:11:39 INFO - 'MAIL': '/var/mail/cltbld', 23:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:39 INFO - 'MOZ_NO_REMOTE': '1', 23:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:39 INFO - 'NO_EM_RESTART': '1', 23:11:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:39 INFO - 'PWD': '/builds/slave/test', 23:11:39 INFO - 'SHELL': '/bin/bash', 23:11:39 INFO - 'SHLVL': '1', 23:11:39 INFO - 'TERM': 'linux', 23:11:39 INFO - 'TMOUT': '86400', 23:11:39 INFO - 'USER': 'cltbld', 23:11:39 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:39 INFO - '_': '/tools/buildbot/bin/python'} 23:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:40 INFO - Downloading/unpacking jsonschema 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:11:41 INFO - Downloading/unpacking functools32 (from jsonschema) 23:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:42 INFO - Downloading functools32-3.2.3-2.tar.gz 23:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:11:42 INFO - warning: no files found matching '*.txt' 23:11:42 INFO - no previously-included directories found matching 'build' 23:11:42 INFO - no previously-included directories found matching 'dist' 23:11:42 INFO - no previously-included directories found matching '.git*' 23:11:42 INFO - Installing collected packages: jsonschema, functools32 23:11:42 INFO - Running setup.py install for functools32 23:11:42 INFO - warning: no files found matching '*.txt' 23:11:42 INFO - no previously-included directories found matching 'build' 23:11:42 INFO - no previously-included directories found matching 'dist' 23:11:42 INFO - no previously-included directories found matching '.git*' 23:11:42 INFO - Successfully installed jsonschema functools32 23:11:42 INFO - Cleaning up... 23:11:42 INFO - Return code: 0 23:11:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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 0x7f5c6ea79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, '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': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', '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 23:11: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 23:11: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 23:11:42 INFO - Using env: {'DISPLAY': ':0', 23:11:42 INFO - 'HOME': '/home/cltbld', 23:11:42 INFO - 'LANG': 'en_US.UTF-8', 23:11:42 INFO - 'LANGUAGE': 'en_US:en', 23:11:42 INFO - 'LOGNAME': 'cltbld', 23:11:42 INFO - 'MAIL': '/var/mail/cltbld', 23:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:42 INFO - 'MOZ_NO_REMOTE': '1', 23:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:42 INFO - 'NO_EM_RESTART': '1', 23:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:42 INFO - 'PWD': '/builds/slave/test', 23:11:42 INFO - 'SHELL': '/bin/bash', 23:11:42 INFO - 'SHLVL': '1', 23:11:42 INFO - 'TERM': 'linux', 23:11:42 INFO - 'TMOUT': '86400', 23:11:42 INFO - 'USER': 'cltbld', 23:11:42 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:42 INFO - '_': '/tools/buildbot/bin/python'} 23:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:42 INFO - Downloading/unpacking flake8 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:11:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:11:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:11:45 INFO - no previously-included directories found matching 'docs/_build' 23:11:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:11:45 INFO - Running setup.py install for pep8 23:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:11:45 INFO - no previously-included directories found matching 'docs/_build' 23:11:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:11:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:11:45 INFO - Cleaning up... 23:11:45 INFO - Return code: 0 23:11:45 INFO - Running post-action listener: _resource_record_post_action 23:11:45 INFO - Running post-action listener: _start_resource_monitoring 23:11:45 INFO - Starting resource monitoring. 23:11:45 INFO - ##### 23:11:45 INFO - ##### Finished create-virtualenv step (success) 23:11:45 INFO - ##### 23:11:45 INFO - ##### 23:11:45 INFO - ##### Running install step. 23:11:45 INFO - ##### 23:11:45 INFO - Running pre-action listener: _resource_record_pre_action 23:11:45 INFO - Running main action method: install 23:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:45 INFO - Reading from file tmpfile_stdout 23:11:45 INFO - Detecting whether we're running mozinstall >=1.0... 23:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:11:45 INFO - Reading from file tmpfile_stdout 23:11:45 INFO - Output received: 23:11:45 INFO - Usage: mozinstall [options] installer 23:11:45 INFO - Options: 23:11:45 INFO - -h, --help show this help message and exit 23:11:45 INFO - -d DEST, --destination=DEST 23:11:45 INFO - Directory to install application into. [default: 23:11:45 INFO - "/builds/slave/test"] 23:11:45 INFO - --app=APP Application being installed. [default: firefox] 23:11:45 INFO - mkdir: /builds/slave/test/build/application 23:11:45 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'] 23:11:45 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 23:12:02 INFO - Reading from file tmpfile_stdout 23:12:02 INFO - Output received: 23:12:02 INFO - /builds/slave/test/build/application/firefox/firefox 23:12:02 INFO - Running post-action listener: _resource_record_post_action 23:12:02 INFO - ##### 23:12:02 INFO - ##### Finished install step (success) 23:12:02 INFO - ##### 23:12:02 INFO - ##### 23:12:02 INFO - ##### Running run-tests step. 23:12:02 INFO - ##### 23:12:02 INFO - Running pre-action listener: _resource_record_pre_action 23:12:02 INFO - Running main action method: run_tests 23:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:12:02 INFO - Python 2.7.3 23:12:02 INFO - Return code: 0 23:12:02 INFO - grabbing minidump binary from tooltool 23:12: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')]} 23:12: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 0x273f9c0>, '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 0x27408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:12: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 23:12: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 23:12: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 23:12:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:12:02 INFO - Return code: 0 23:12:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:12:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:12:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:12:03 INFO - Return code: 0 23:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 23:12:03 INFO - Using env: {'DISPLAY': ':0', 23:12:03 INFO - 'HOME': '/home/cltbld', 23:12:03 INFO - 'LANG': 'en_US.UTF-8', 23:12:03 INFO - 'LANGUAGE': 'en_US:en', 23:12:03 INFO - 'LOGNAME': 'cltbld', 23:12:03 INFO - 'MAIL': '/var/mail/cltbld', 23:12:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:12:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:03 INFO - 'MOZ_NO_REMOTE': '1', 23:12:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:03 INFO - 'NO_EM_RESTART': '1', 23:12:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:03 INFO - 'PWD': '/builds/slave/test', 23:12:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:12:03 INFO - 'SHELL': '/bin/bash', 23:12:03 INFO - 'SHLVL': '1', 23:12:03 INFO - 'TERM': 'linux', 23:12:03 INFO - 'TMOUT': '86400', 23:12:03 INFO - 'USER': 'cltbld', 23:12:03 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572', 23:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:03 INFO - '_': '/tools/buildbot/bin/python'} 23:12:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:12:03 INFO - mozversion application_buildid: 20160513203052 23:12:03 INFO - mozversion application_changeset: 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 23:12:03 INFO - mozversion application_display_name: Nightly 23:12:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:12:03 INFO - mozversion application_name: Firefox 23:12:03 INFO - mozversion application_remotingname: firefox 23:12:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:12:03 INFO - mozversion application_vendor: Mozilla 23:12:03 INFO - mozversion application_version: 49.0a1 23:12:03 INFO - mozversion platform_buildid: 20160513203052 23:12:03 INFO - mozversion platform_changeset: 44dc9457053b0f6ecacbc01c7d11a8b457c3cb55 23:12:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:12:03 INFO - mozversion platform_version: 49.0a1 23:12:03 INFO - using testdate: 1463206323 23:12:03 INFO - actual date: 1463206323 23:12:03 INFO - starting webserver on 'localhost:57072' 23:12:03 INFO - SUITE-START | Running 2 tests 23:12:03 INFO - TEST-START | dromaeo_css 23:12:03 INFO - operating with platform_type : linux_ 23:12:03 INFO - Initialising browser for dromaeo_css test... 23:12:03 INFO - TEST-INFO | started process 24511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHiMN6c/profile http://localhost:57072/getInfo.html) 23:12:07 INFO - PROCESS | 24511 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 23:12:08 INFO - PROCESS | 24511 | __metrics Screen width/height:1600/1200 23:12:08 INFO - PROCESS | 24511 | colorDepth:24 23:12:08 INFO - PROCESS | 24511 | Browser inner width/height: 1024/697 23:12:08 INFO - PROCESS | 24511 | __metrics 23:12:09 ERROR - PROCESS | 24511 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:12:10 INFO - TEST-INFO | 24511: exit 0 23:12:10 INFO - Browser initialized. 23:12:10 INFO - Running cycle 1/1 for dromaeo_css test... 23:12:10 INFO - TEST-INFO | started process 24593 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHiMN6c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:21 INFO - PROCESS | 24593 | RSS: Main: 152993792 23:12:21 INFO - PROCESS | 24593 | 23:13:12 INFO - PROCESS | 24593 | 1463206392768 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] 23:15:20 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57072/tests/dromaeo/cssquery-ext.html) 23:15:21 INFO - PROCESS | 24593 | RSS: Main: 264503296 23:15:21 INFO - PROCESS | 24593 | 23:18:23 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-ext.html (next: http://localhost:57072/tests/dromaeo/cssquery-jquery.html) 23:18:24 INFO - PROCESS | 24593 | RSS: Main: 236744704 23:18:24 INFO - PROCESS | 24593 | 23:21:26 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57072/tests/dromaeo/cssquery-mootools.html) 23:21:27 INFO - PROCESS | 24593 | RSS: Main: 237731840 23:21:27 INFO - PROCESS | 24593 | 23:24:29 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57072/tests/dromaeo/cssquery-prototype.html) 23:24:29 INFO - PROCESS | 24593 | RSS: Main: 227078144 23:24:29 INFO - PROCESS | 24593 | 23:27:32 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57072/tests/dromaeo/cssquery-yui.html) 23:27:32 INFO - PROCESS | 24593 | RSS: Main: 198840320 23:27:32 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | Cycle 1(1): loaded http://localhost:57072/tests/dromaeo/cssquery-yui.html (next: http://localhost:57072/tests/dromaeo/cssquery-dojo.html) 23:30:35 INFO - PROCESS | 24593 | RSS: Main: 295530496 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | __start_tp_report 23:30:35 INFO - PROCESS | 24593 | _x_x_mozilla_page_load 23:30:35 INFO - PROCESS | 24593 | _x_x_mozilla_page_load_details 23:30:35 INFO - PROCESS | 24593 | |i|pagename|runs| 23:30:35 INFO - PROCESS | 24593 | |0;dojo.html;3089.2276930767307;3105.052958847552;3125.32805446829;3135.7804953653254;3157.636871759747;5173.689578625281;5275.736213189344;5298.708571028592;5334.439883812202;5351.197320401937;5342.492463215998;5363.544098751601;5367.731613419333;5427.538659213971;5460.918086228699;4813.302071199682;4897.853064408074;4917.631177661672;4921.089598424285;5033.723145227011;4945.480724523927;4975.27858460523;4998.625103117262;5018.899622007558;5019.322391477141;4744.4306683198065;4760.095581839457;4780.713157210578;4784.354112194844;4788.760561971888;4786.880327991793;4909.509049095098;4910.410750709938;4918.286848406961;4953;4743.976280118612;4780.49804770501;4864.075825593126;4882.584980276645;4927.728974906415;5262.473752624745;5304.575633949314;5320.893582128375;5345.412004679482;5351.598630102758;5712.914306285409;5752.194692743019;5775.5957083004205;5780.624259423124;5784.508316793078;5600.131979543177;5665.320161580596;5701.657900525982;5775.711214439261;5839.824805255849;5388.030154572176;5418.729063546807;5430.02259593277;5437.619366644337;5438.157085651691;5916.911246331309;5933.436323549256;5935;5937;5939.940600594025;5549.306336707911;5573.6098473106495;5583.748731307101;5615.634983726045;5688.431156884279;5731.512821410143;5743.023685973395;5744.712764361765;5744.885102298014;5751.798687046017;5704.486596206279;5708;5708.0581704019205;5711.343195532567;5717.342505611908;5657.094864821609;5664.178694089334;5667.971660141673;5713.628614140068;5743.167240750151;4364.301711726109;4367.879135399292;4375.518692943773;4376.715513491613;4405.581469760367;3840.0399900024995;3887.6695480883877;3897.5517815451017;3908.101136738509;3953.8418463261146;5374.677519348852;5386.165144402624;5413.133898576209;5456.508914197741;5459.2902922619805;5743.2246646703;5746.712664366765;5771.163181338682;5771.769129234867;5789.160571717078;5318.335208098988;5387.515123638891;5422.322209723785;5431.1853222016225;5444.727763611803;4793.712377257376;4813.518648135228;4835.395575553056;4855.562999330077;4860.73265970368;6004.969975150097;6012.879742405127;6022.759089636453;6029.638221706711;6040.335563088057;5658.434156584309;5698.6580805151825;5710.828675139753;5754.338251101177;5832.533397328203;5953.732082056231;5957.851053723692;5960.791372301948;5962.284525856925;5966.284045914518;5663.716814159358;5665.943340566541;5669.659820410789;5679.148127780866;5681.545476361798;5571.749271282721;5740.712964351849;5756.913646295226;5783.306003279633;5858.384869588632;5701.771929122789;5705;5705.372409035086;5705.429457054427;5714.514266287412;5394.730263486731;5636;5669.177969194526;5694.772209111589;5702.401247868914;5420.674759514442;5460.399356070909;5492.286002819608;5532.75102620375;5557.083081291542;5663.603547751618;5667.971660141673;5681.715914204191;5716.5426765859465;5718.771249150153;5389.353277606712;5506.421825708403;5534.861628459321;5542.0855558832345;5576.972115139399 23:30:35 INFO - PROCESS | 24593 | |1;ext.html;605.3008774865029;634.2674211285965;645.5610185074149;652.3378770547895;666.1839246922517;31718.36563268736;43220.56779432209;43565.78217108914;43594;47160;44670;45403.545964540346;45436.3184552232;45443.77278113613;45455.31817022739;8856.512891790933;9073.228775554086;9079.046700096495;9084.137006984325;9084.273258139367;8986.730398088068;9100.726978190633;9109.68116115936;9113.225375843062;9114.680986165486;9152.542372881362;9188.356815022951;9191.126842949909;9193.126652967958;9193.35646504745;9088.681896133636;9134.954325228398;9146.359754817166;9152.633894644207;9154.771130721718;8982.685606003824;9044.457332560001;9046.502442365667;9054.637814487412;9058.411203271766;42716;43195;43207.351889721365;43217;43225.35161972573;3731.8320675569666;3751.3060083884297;3852.152526444178;3858.6913046956174;3898.9805050974846;32567.837160814044;32722.113075747664;32845.01464956055;32859;33881.66118338834;4316.58992395725;4326.351047342892;4332.133573285356;4371.540988196225;4420.447444069491;240873;241151;338527;338919;341388;50540.49459505358;56363;57164.71417642885;57164.71417642885;57225.427745722845;59189;59766.103508447406;59823.700881495315;66574.66712666406;66639;16990.57523561921;17154.828451715573;17164.82835171632;17196;17202.569935751457;12700.682482937817;13001.934990324988;13641.931790340985;13813.102148360324;13836.654083647789;15942.442014529435;16191.433299834449;16327.673446531004;16763.832361676472;16771.664566708598;6372.4583410409705;6448.613083214928;6600.60396376219;6607.834804129935;6667.433268172163;1015.7054454208345;1145.2907257322242;1164.4673924354236;1177.8115501519715;1223.804191329383;32701.345973080406;33303.50044749331;33320.333593327996;33364;33687.66312336894;1117.94969226385;1165.5221359242669;1167.6380322100176;1209.1535924852637;1258.704204511939;17064.48806535806;17635.647287054187;17654.823451765576;17705.731931629773;17775.377861774778;501885;509357;511179;511272;512299;57265.713671431375;58100.4189958095;58124;58155.41844581585;58243;1303.3743802974436;1392.5822253323963;1395.114102544871;1426.985730142706;1441.2001339256813;32578.674213257564;34567.30865382628;35436.822815886786;35479.11302217465;36787.63212367949;57375.71312143413;61979;63907.68046159925;63925;68639.65680171567;481958;490715;493239;493284;493963;40461;47700.522994770996;51123.23315150201;51177.74411127921;52312.47687523076;48678;52561;53154.46845531495;53945.46054539561;54260.45739542554;43872.34191487067;43979.560204398826;44455.33317000183;44695.776521117186;45703.31445028261;43939;44674.77662611666;45163.54836451593;45191.77404112958;45350.77324613488;44596.77701611471;45331.32003020023;45442.77278613586;45620.54379456296;45667.314990274506;36668.81665591655;38855.222895542494;39317.2136557273;39914.2017159661;40463.595364045985;29419.41161176807;29634.40731185321;29869.850650746608;30224;30334.69665303407 23:30:35 INFO - PROCESS | 24593 | |2;jquery.html;405.1532297498232;407.4194273160745;409.54540460089305;415.4246368779242;417.40102952263504;13978.860211397909;18368.448946531593;19863.30478433255;19872.60254794903;19881;15537.611559711013;19031.904840475778;19104.617907641838;19315.806841931575;20141.590088693796;5671.8014869479475;5902.940970590304;5903.527717782588;5909.438603332676;5919.319278282988;5726.599138060337;5808.1287806829105;5812.505936995361;5814.156947242647;5821.738021789009;4108.589141085898;6106.542009349294;6423.4861211103;6433.613983161002;6547.345265473462;6386.840328991812;6475.83810404739;6478.449331806802;6493.512986526006;6523.641199734013;5631.9718401408145;5653.547716182696;5692.9146062809095;5753.482186603226;5769.394213607553;20148.39554813358;23677.644835327483;23780.55181998618;23898.402539936465;24299.878500607385;2383.499465112311;2393.042782886842;2399.256230568512;2997.3405850712807;3012.4276387486307;14285.57143285703;14782.334794934253;14847.851521484863;14903.88220883438;15204.695906081926;1979.495228716668;2038.398672391648;2047.6518991771434;2104.8210902073342;2173.5435558532727;81809.00100988886;82265.58867205626;84589.57705211497;85168.57415712943;85221.57389213076;10518.842217366746;10742.409167495707;10745.871683473279;11116.388598627153;11239.325640461599;15516.069035857885;15610.063396196265;15698;15698.529044128694;15728.213589320489;8390.664373424994;8706.259967902672;8740.562971851383;8878.73363799087;8881.067487913806;8646.610902509403;8697.912760904886;8789.252913502296;8854.955725221462;8899.199072083415;5978.940210597925;6598.538102332886;6678.298778628174;6809.659517024129;6937.68780404883;1828.9634207316046;1941.4952112450865;1993.700944858254;2010.819026287612;2028.766691830429;982.8525721141743;1019.3679918450608;1106.2145876427664;1110.9723505757142;1132.7621199548023;20428.182872684927;21452.89273553622;21707.782922170893;21793.128274869145;22022.669659955114;1005.9295849290626;1071.164491696478;1113.4599719136213;1113.8106521891298;1125.6341688951125;11703.122265830098;12176.269423834598;12272.877271227351;12344.62966111018;12403.759624037524;453070;455585;466091;467913;469390;87896.56051719701;94606;95131;95207.52396237974;95362.52318738501;1073.4793625091825;1080.1682704317789;1119.5409881948362;1129.0910816792561;1136.7044568412107;17569.209385577935;17796.644067118843;17823.287068517115;18032.368867090114;18105.456836294932;30442.84778576093;36827.447588286734;39503.60496394999;40478.595214047484;40922.18155636811;53349.73325133349;54106.45893541014;54691.72654136704;55110.6867951544;56384;7741.496802707806;8622.396432249932;8839.823203536022;9026.68406605792;9289.6284148636;8472.957635211784;12728.045396595478;12926.353682316034;13341.466341346237;13756.312184390541;11120.944395277971;11248.831267531157;11271.943640282074;11322.94338528302;11570.132240082195;6556.967215163893;9883.703488895344;11189.104871610414;11344.262622929484;12030.819537706766;11546.711332216762;11788.29270243788;12085.335306558223;12338.3830808461;12369.443375048324;8749.8687519686;8870.201681848665;8976.41653292534;9090.090990900857;9317.922162707657;11737.178397512092;11936.28382297065;12069.094817888517;12105.213161144497;12146.817797732865 23:30:35 INFO - PROCESS | 24593 | |3;mootools.html;1410.083445760255;1532.7547592385217;1571.9685606287874;1747.7553142560037;1857.322077441734;7840.33357164641;8537.615807288672;8774.824503509933;8806.251468625174;8919.866202006975;8757.518336491506;8934.419262747933;8998.730038098867;9005.369624126313;9009.774755631097;2366.230974933145;2392.7009123859516;2393.258089992108;2395.161693407302;2395.6047252203366;2323.1984965186984;2349.3304408243653;2365.7161140663143;2366.715994080713;2372.9881350593223;2344.6248600223967;2350.894209760565;2360.84654497458;2364.13708996216;2385.2009576791797;2296.6555016747457;2319.82601304902;2323.9767602323927;2325.7790509901615;2332.0788288626068;2276.0781883337277;2278.362058623588;2280.5438912217624;2281.646344816556;2283.041122728458;8762.780930476725;8912.955435222848;8963.596638151299;8969.820603587958;9010.774730631787;5429.891402171935;5553.0837411827;5559.19391688207;5562.749676264578;5563.415841336642;3557.6442355764475;3567.0547304964207;3622.9275414491826;3701.777893326409;3718.7954662493553;1238.8017917133307;1278.181963543333;2190.113004233288;2200.460887082659;2201.5816994770944;11993.100517461138;12375.948044416235;12443;12465.501379944792;12482.563110291187;4510.887227819265;4580.152671755736;4638.907221855544;4644.86065418038;4660.464046634613;9938.900610993798;9982.201423886072;9995.900040999497;10000.899991000142;10009.149222316039;5327.334083239595;5329.680219186861;5330.067238233371;5330.253764472977;5332.546733527616;4435.489918659331;4552.635789136861;4900.828471003498;4998.975005124951;5066.923996140061;3940.133170702441;3944.6449819516383;3988.80055997199;4030.1133750574827;4059.573744756817;1208.4501551793912;1211.4790640024873;1239.4360565942475;1241.205628397826;1257.076049103908;750.6772088002211;904.1997831918799;971.140540621555;981.1954197558076;1012.0739523336213;5876.382979787061;5964.522838172936;6059.8485037873525;6069.423404776539;6393.712282947278;983.5770618633914;991.1723710701644;1034.912032477248;1037.1961729659574;1064.8136576099153;3991.3015222335976;4087.8773636790943;4146.087860670647;4149.232392007489;4175.665946724254;11126.721831954104;11447.885521144683;11516.481758320728;11521.654350369334;11522;11406;11705.531778728926;11720.531178752924;11820.70448238801;11823.467943942544;8165.877511837327;8404.663813447394;8489.278411335106;8492.957535212285;8599.742007739778;6020.819375418744;6028.57799953999;6051.788187413419;6182.072689096672;6220.440160385587;10154.238432117769;10502.579896804042;10714.250002500063;10732.946335268274;10741.301815381976;10525.526351314362;10800.405977671304;10801.621943231949;10810;10884.346939183675;7172.713091476491;7452.254774522505;7663.540187588763;7803.063632364153;7931.960340198262;8498;8515.914840851512;8551.657933682583;8597.914020859962;8600.956995214983;7614.086309642878;7753.573553454614;7789.649465774167;7899.802504937423;7920.801979950547;7392.371648409945;7453.81365465846;7465.6267186641535;7604.2395760423515;7613.9238607613215;6921.204061532988;6963.059986901703;6985.5808651481075;7002.964985175042;7013.263607321362;4846.975765121152;5033.723145227048;5128.512791284822;5131.820386286462;5138.511841375063;4982.277569752293;5048.293238946624;5052.9494705052475;5063.898722025465;5168.9741551292 23:30:35 INFO - PROCESS | 24593 | |4;prototype.html;316.7798380125812;323.97894136881104;333.0707326558901;338.6900985598178;340.67125224158684;5329.999999999995;5383.43473935236;5384.6769193848395;5401.081816091264;5424.945750542493;5077.492250774921;5087.186050231963;5196.740162991845;5263.894722105565;5335.679859208451;4078.8164532595956;4087.3460246360587;4113.614103819792;4167.5832416758385;4175.812088456019;4103.692223083266;4111.691623128262;4137.710360274782;4149.232392007473;4152.774931395231;3954.1300913799064;4074.7351422157467;4129.194807012634;4163.250614889319;4171.687123465752;4126.442930204414;4143.461350024508;4160.188763191179;4162.146759914211;4170.937435938464;3682.871099511531;3689.6679298863232;3711.740178187528;3711.832967516468;3715.8327875245677;4981.452040275567;5096;5105.208692652608;5118.6416950813455;5151.562117220041;4743.549362810528;4760.095581839474;4779.641526885463;4797.208460603996;4802.087603355351;5544.86330302287;5545.417731138213;5545.750441230154;5557.3886872444;5566.248556444869;3877.418387241908;4008.6993475489453;4018.839246430139;4027.254957832781;4058.9797051014557;5684.744186511617;5712.80005199816;5746;5767.6539407635255;5782.421757824226;4075.857344992911;4167.35373038123;4169.624733773975;4171.520275168334;4174.144300418407;3883.825227864753;4073.4704488416314;4079;4110.314770943899;4146.673064619293;4649.8840278332955;4664.134346261522;4671.696339737906;4685.765711714401;4687.906241875144;5498.890022199534;5512.503874651301;5557.5553955683445;5581.190727344512;5652.406497317805;1374.8693874081946;1406.3741634972544;1421.8720315171477;1424.0672359604475;1436.6552027513321;907.3648446087633;907.3875134284332;916.6699988004314;917.4449458077901;920.093707697917;4779;4800.711957282574;4808.783604737795;4820.421549414093;4843.491433399442;4995.775190116454;5050.267711181858;5051.419086805065;5067.78373190439;5072.746362681851;4482.7758612069265;4761.214399624023;4809.254565542346;4817.8313759018265;4847.200211965057;2695;2714.267147870064;2714.4978179036943;2714.606382074588;2719.966412763177;5128.358955130609;5132.458603563529;5196.662216955961;5217.895642087137;5252.579793616502;4808.831690890802;5036.722980236123;5050.570701490341;5098.97450512745;5108.1865937592365;5154.30416893722;5190.844274671766;5223.895522089537;5264.499872512105;5301.416844147141;5019.7490125494305;5042.697438153723;5081.618878584195;5125.641205115755;5129.512696293846;4777.904441911072;5021.774020169174;5023.799048038184;5066.544011039025;5077.7461126944245;5287.524122828964;5295.27903431394;5303.178007408857;5305.495977882095;5310.787568497217;3674.4855720198657;3682.944755828611;3687.6128006559998;3832.3485007548225;3937.7637341759587;5602.831915042555;5665.858353541194;5715.542756579381;5729.770809167753;5757.481826635623;5575.135853942646;5575.944240557543;5580.860478487908;5600.775968961196;5603.495685388335;5297.364316281916;5519.151084386775;5534.391216966211;5538.529225015919;5563.610547261653;5403.675779453245;5438.809641662523;5439.857629897766;5440.0959664117245;5455.754491047833;5398.40617532079;5518.641288316247;5528.391876893458;5568.192612071146;5619.6512836919665;5404.78380864761;5441.455854414527;5442.918356224581;5449.591280653887;5470.398256191736 23:30:35 INFO - PROCESS | 24593 | |5;yui.html;786.7993661616287;814.4543156085423;842.4271495383141;849.0999540487088;871.1158174452931;14877.553673389788;15075;15326.310316035777;15460.613484662888;15472.226388680578;13628.250446225453;14023.228722420263;14035.298235088256;14043.36804843782;14170.574882753534;701.4003027411566;817.0930267403178;822.8148666550009;826.5990994367727;840.6931470013462;821.2772759971217;823.9217274358926;827.9213474719892;828.1015098618007;830.7756905635475;723.1936390924108;891.4517571693427;891.5185799668204;891.7413949954505;893.9955300223489;733.7578599062297;738.114262884538;864.0883867519764;874.6938571499987;874.7725591346232;646.0406296649472;648.627039452312;651.2217899609968;651.3974573519523;651.5569412799294;14275.21486318252;14338.928305358406;14379.568612941628;14380.137191768423;14380.137191768526;12949.223046617139;13496.730065398637;13938.93030534851;13946.511872084428;14048.789268160986;12040.879591204064;12057.819132713017;12373.443195056245;12500.562480313145;12764.80852787209;4366.235908715994;4463.553644635511;4475.865724028285;4491.977540112279;4578.885527861797;58509.122363164584;58516.122258166164;58830.11754823681;58957.705211473665;60886.695566521885;10791.676249712522;10977.176711746491;11011.174161937886;11112.05547528453;11501.539938402537;20616;20616.58766824655;20639.8968005159;20642;20665;7877.291043806085;8008.079070906921;8067.556284404297;8300;8329.458585191944;4633.582977531971;4648.9075067359145;4697.060587882369;4828.251620998751;4902.681325713817;3334.866605335808;3433.5193072969805;3447.413939630269;3453.6028356738793;3456.0495863637198;617.9845503862349;621.7326549583722;630.0328994992756;632.0550776589029;646.0599827251338;658.1904257762883;694.2086021934998;748.3489364253135;749.2208103572333;782.1005843280274;9708.223342132613;9845.45849978258;9881;9913.107820296207;9924.106830385155;641.1088586864263;684.3327755438507;702.8278071872322;716.2300526933567;761.466973118819;4465.955340446619;4540.818367265272;4555.2256116460285;4568.406107206042;4685.976570117127;70793.29206707867;71859.64070179721;74685;74771.25228747752;74807.62596186984;61621.38378616156;61640.691796540734;62944.68527657333;64566;64695.67652161709;56474;57143.99999999917;57594;57736.71131644399;58888.11667824986;8719.12808719123;8809.383343165917;8820.514871682119;8839.558022098741;8860.468371897707;37162.256754865295;37188.44217336796;37454.438183426726;37580.436293456165;38114;49548.75225623849;50133;50322.74838625784;50448.747756260986;51387;11154.219204655921;11560.132990025613;11835.171537992257;12011.819822702491;12124.333161676192;11667.59163429276;11813.704657383634;12245.265284082983;12761.553345632858;12867.549635762703;11608.535658573563;11635.941820290844;11893.286402815858;12117.454714537691;12236.571719989757;11817.527298907948;11909.166358354832;11934;12121.636350909486;12549.686257843627;11044.723881903017;11477;11913.285202887855;12279.754404911673;12525.373731313215;9835.655752048644;9943.105120539187;10042;10082.69751907444;10278.845817312897;9334.533273336441;9442.433453992782;9487.66793162236;9488.335816493056;9619.807603848023 23:30:35 INFO - PROCESS | 24593 | __end_tp_report 23:30:35 INFO - PROCESS | 24593 | __start_cc_report 23:30:35 INFO - PROCESS | 24593 | _x_x_mozilla_cycle_collect,877 23:30:35 INFO - PROCESS | 24593 | __end_cc_report 23:30:35 INFO - PROCESS | 24593 | __startTimestamp1463207435586__endTimestamp 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | ------- Summary: start ------- 23:30:35 INFO - PROCESS | 24593 | Number of tests: 6 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#0] dojo.html Cycles:165 Average:5331.73 Median:5534.86 stddev:613.53 (11.1%) stddev-sans-first:589.66 23:30:35 INFO - PROCESS | 24593 | Values: 3089.2 3105.1 3125.3 3135.8 3157.6 5173.7 5275.7 5298.7 5334.4 5351.2 5342.5 5363.5 5367.7 5427.5 5460.9 4813.3 4897.9 4917.6 4921.1 5033.7 4945.5 4975.3 4998.6 5018.9 5019.3 4744.4 4760.1 4780.7 4784.4 4788.8 4786.9 4909.5 4910.4 4918.3 4953.0 4744.0 4780.5 4864.1 4882.6 4927.7 5262.5 5304.6 5320.9 5345.4 5351.6 5712.9 5752.2 5775.6 5780.6 5784.5 5600.1 5665.3 5701.7 5775.7 5839.8 5388.0 5418.7 5430.0 5437.6 5438.2 5916.9 5933.4 5935.0 5937.0 5939.9 5549.3 5573.6 5583.7 5615.6 5688.4 5731.5 5743.0 5744.7 5744.9 5751.8 5704.5 5708.0 5708.1 5711.3 5717.3 5657.1 5664.2 5668.0 5713.6 5743.2 4364.3 4367.9 4375.5 4376.7 4405.6 3840.0 3887.7 3897.6 3908.1 3953.8 5374.7 5386.2 5413.1 5456.5 5459.3 5743.2 5746.7 5771.2 5771.8 5789.2 5318.3 5387.5 5422.3 5431.2 5444.7 4793.7 4813.5 4835.4 4855.6 4860.7 6005.0 6012.9 6022.8 6029.6 6040.3 5658.4 5698.7 5710.8 5754.3 5832.5 5953.7 5957.9 5960.8 5962.3 5966.3 5663.7 5665.9 5669.7 5679.1 5681.5 5571.7 5740.7 5756.9 5783.3 5858.4 5701.8 5705.0 5705.4 5705.4 5714.5 5394.7 5636.0 5669.2 5694.8 5702.4 5420.7 5460.4 5492.3 5532.8 5557.1 5663.6 5668.0 5681.7 5716.5 5718.8 5389.4 5506.4 5534.9 5542.1 5577.0 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#1] ext.html Cycles:180 Average:61236.41 Median:44672.39 stddev:117720.68 (263.5%) stddev-sans-first:117962.90 23:30:35 INFO - PROCESS | 24593 | Values: 605.3 634.3 645.6 652.3 666.2 31718.4 43220.6 43565.8 43594.0 47160.0 44670.0 45403.5 45436.3 45443.8 45455.3 8856.5 9073.2 9079.0 9084.1 9084.3 8986.7 9100.7 9109.7 9113.2 9114.7 9152.5 9188.4 9191.1 9193.1 9193.4 9088.7 9135.0 9146.4 9152.6 9154.8 8982.7 9044.5 9046.5 9054.6 9058.4 42716.0 43195.0 43207.4 43217.0 43225.4 3731.8 3751.3 3852.2 3858.7 3899.0 32567.8 32722.1 32845.0 32859.0 33881.7 4316.6 4326.4 4332.1 4371.5 4420.4 240873.0 241151.0 338527.0 338919.0 341388.0 50540.5 56363.0 57164.7 57164.7 57225.4 59189.0 59766.1 59823.7 66574.7 66639.0 16990.6 17154.8 17164.8 17196.0 17202.6 12700.7 13001.9 13641.9 13813.1 13836.7 15942.4 16191.4 16327.7 16763.8 16771.7 6372.5 6448.6 6600.6 6607.8 6667.4 1015.7 1145.3 1164.5 1177.8 1223.8 32701.3 33303.5 33320.3 33364.0 33687.7 1117.9 1165.5 1167.6 1209.2 1258.7 17064.5 17635.6 17654.8 17705.7 17775.4 501885.0 509357.0 511179.0 511272.0 512299.0 57265.7 58100.4 58124.0 58155.4 58243.0 1303.4 1392.6 1395.1 1427.0 1441.2 32578.7 34567.3 35436.8 35479.1 36787.6 57375.7 61979.0 63907.7 63925.0 68639.7 481958.0 490715.0 493239.0 493284.0 493963.0 40461.0 47700.5 51123.2 51177.7 52312.5 48678.0 52561.0 53154.5 53945.5 54260.5 43872.3 43979.6 44455.3 44695.8 45703.3 43939.0 44674.8 45163.5 45191.8 45350.8 44596.8 45331.3 45442.8 45620.5 45667.3 36668.8 38855.2 39317.2 39914.2 40463.6 29419.4 29634.4 29869.9 30224.0 30334.7 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#2] jquery.html Cycles:180 Average:28470.41 Median:13145.72 stddev:76386.88 (581.1%) stddev-sans-first:76572.10 23:30:35 INFO - PROCESS | 24593 | Values: 405.2 407.4 409.5 415.4 417.4 13978.9 18368.4 19863.3 19872.6 19881.0 15537.6 19031.9 19104.6 19315.8 20141.6 5671.8 5902.9 5903.5 5909.4 5919.3 5726.6 5808.1 5812.5 5814.2 5821.7 4108.6 6106.5 6423.5 6433.6 6547.3 6386.8 6475.8 6478.4 6493.5 6523.6 5632.0 5653.5 5692.9 5753.5 5769.4 20148.4 23677.6 23780.6 23898.4 24299.9 2383.5 2393.0 2399.3 2997.3 3012.4 14285.6 14782.3 14847.9 14903.9 15204.7 1979.5 2038.4 2047.7 2104.8 2173.5 81809.0 82265.6 84589.6 85168.6 85221.6 10518.8 10742.4 10745.9 11116.4 11239.3 15516.1 15610.1 15698.0 15698.5 15728.2 8390.7 8706.3 8740.6 8878.7 8881.1 8646.6 8697.9 8789.3 8855.0 8899.2 5978.9 6598.5 6678.3 6809.7 6937.7 1829.0 1941.5 1993.7 2010.8 2028.8 982.9 1019.4 1106.2 1111.0 1132.8 20428.2 21452.9 21707.8 21793.1 22022.7 1005.9 1071.2 1113.5 1113.8 1125.6 11703.1 12176.3 12272.9 12344.6 12403.8 453070.0 455585.0 466091.0 467913.0 469390.0 87896.6 94606.0 95131.0 95207.5 95362.5 1073.5 1080.2 1119.5 1129.1 1136.7 17569.2 17796.6 17823.3 18032.4 18105.5 30442.8 36827.4 39503.6 40478.6 40922.2 53349.7 54106.5 54691.7 55110.7 56384.0 7741.5 8622.4 8839.8 9026.7 9289.6 8473.0 12728.0 12926.4 13341.5 13756.3 11120.9 11248.8 11271.9 11322.9 11570.1 6557.0 9883.7 11189.1 11344.3 12030.8 11546.7 11788.3 12085.3 12338.4 12369.4 8749.9 8870.2 8976.4 9090.1 9317.9 11737.2 11936.3 12069.1 12105.2 12146.8 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#3] mootools.html Cycles:180 Average:5908.89 Median:4641.88 stddev:3356.73 (72.3%) stddev-sans-first:3349.12 23:30:35 INFO - PROCESS | 24593 | Values: 1410.1 1532.8 1572.0 1747.8 1857.3 7840.3 8537.6 8774.8 8806.3 8919.9 8757.5 8934.4 8998.7 9005.4 9009.8 2366.2 2392.7 2393.3 2395.2 2395.6 2323.2 2349.3 2365.7 2366.7 2373.0 2344.6 2350.9 2360.8 2364.1 2385.2 2296.7 2319.8 2324.0 2325.8 2332.1 2276.1 2278.4 2280.5 2281.6 2283.0 8762.8 8913.0 8963.6 8969.8 9010.8 5429.9 5553.1 5559.2 5562.7 5563.4 3557.6 3567.1 3622.9 3701.8 3718.8 1238.8 1278.2 2190.1 2200.5 2201.6 11993.1 12375.9 12443.0 12465.5 12482.6 4510.9 4580.2 4638.9 4644.9 4660.5 9938.9 9982.2 9995.9 10000.9 10009.1 5327.3 5329.7 5330.1 5330.3 5332.5 4435.5 4552.6 4900.8 4999.0 5066.9 3940.1 3944.6 3988.8 4030.1 4059.6 1208.5 1211.5 1239.4 1241.2 1257.1 750.7 904.2 971.1 981.2 1012.1 5876.4 5964.5 6059.8 6069.4 6393.7 983.6 991.2 1034.9 1037.2 1064.8 3991.3 4087.9 4146.1 4149.2 4175.7 11126.7 11447.9 11516.5 11521.7 11522.0 11406.0 11705.5 11720.5 11820.7 11823.5 8165.9 8404.7 8489.3 8493.0 8599.7 6020.8 6028.6 6051.8 6182.1 6220.4 10154.2 10502.6 10714.3 10732.9 10741.3 10525.5 10800.4 10801.6 10810.0 10884.3 7172.7 7452.3 7663.5 7803.1 7932.0 8498.0 8515.9 8551.7 8597.9 8601.0 7614.1 7753.6 7789.6 7899.8 7920.8 7392.4 7453.8 7465.6 7604.2 7613.9 6921.2 6963.1 6985.6 7003.0 7013.3 4847.0 5033.7 5128.5 5131.8 5138.5 4982.3 5048.3 5052.9 5063.9 5169.0 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#4] prototype.html Cycles:180 Average:4502.83 Median:5050.99 stddev:1297.14 (25.7%) stddev-sans-first:1262.16 23:30:35 INFO - PROCESS | 24593 | Values: 316.8 324.0 333.1 338.7 340.7 5330.0 5383.4 5384.7 5401.1 5424.9 5077.5 5087.2 5196.7 5263.9 5335.7 4078.8 4087.3 4113.6 4167.6 4175.8 4103.7 4111.7 4137.7 4149.2 4152.8 3954.1 4074.7 4129.2 4163.3 4171.7 4126.4 4143.5 4160.2 4162.1 4170.9 3682.9 3689.7 3711.7 3711.8 3715.8 4981.5 5096.0 5105.2 5118.6 5151.6 4743.5 4760.1 4779.6 4797.2 4802.1 5544.9 5545.4 5545.8 5557.4 5566.2 3877.4 4008.7 4018.8 4027.3 4059.0 5684.7 5712.8 5746.0 5767.7 5782.4 4075.9 4167.4 4169.6 4171.5 4174.1 3883.8 4073.5 4079.0 4110.3 4146.7 4649.9 4664.1 4671.7 4685.8 4687.9 5498.9 5512.5 5557.6 5581.2 5652.4 1374.9 1406.4 1421.9 1424.1 1436.7 907.4 907.4 916.7 917.4 920.1 4779.0 4800.7 4808.8 4820.4 4843.5 4995.8 5050.3 5051.4 5067.8 5072.7 4482.8 4761.2 4809.3 4817.8 4847.2 2695.0 2714.3 2714.5 2714.6 2720.0 5128.4 5132.5 5196.7 5217.9 5252.6 4808.8 5036.7 5050.6 5099.0 5108.2 5154.3 5190.8 5223.9 5264.5 5301.4 5019.7 5042.7 5081.6 5125.6 5129.5 4777.9 5021.8 5023.8 5066.5 5077.7 5287.5 5295.3 5303.2 5305.5 5310.8 3674.5 3682.9 3687.6 3832.3 3937.8 5602.8 5665.9 5715.5 5729.8 5757.5 5575.1 5575.9 5580.9 5600.8 5603.5 5297.4 5519.2 5534.4 5538.5 5563.6 5403.7 5438.8 5439.9 5440.1 5455.8 5398.4 5518.6 5528.4 5568.2 5619.7 5404.8 5441.5 5442.9 5449.6 5470.4 23:30:35 INFO - PROCESS | 24593 | 23:30:35 INFO - PROCESS | 24593 | [#5] yui.html Cycles:180 Average:15903.49 Median:50385.75 stddev:19613.95 (38.9%) stddev-sans-first:19636.12 23:30:35 INFO - PROCESS | 24593 | Values: 786.8 814.5 842.4 849.1 871.1 14877.6 15075.0 15326.3 15460.6 15472.2 13628.3 14023.2 14035.3 14043.4 14170.6 701.4 817.1 822.8 826.6 840.7 821.3 823.9 827.9 828.1 830.8 723.2 891.5 891.5 891.7 894.0 733.8 738.1 864.1 874.7 874.8 646.0 648.6 651.2 651.4 651.6 14275.2 14338.9 14379.6 14380.1 14380.1 12949.2 13496.7 13938.9 13946.5 14048.8 12040.9 12057.8 12373.4 12500.6 12764.8 4366.2 4463.6 4475.9 4492.0 4578.9 58509.1 58516.1 58830.1 58957.7 60886.7 10791.7 10977.2 11011.2 11112.1 11501.5 20616.0 20616.6 20639.9 20642.0 20665.0 7877.3 8008.1 8067.6 8300.0 8329.5 4633.6 4648.9 4697.1 4828.3 4902.7 3334.9 3433.5 3447.4 3453.6 3456.0 618.0 621.7 630.0 632.1 646.1 658.2 694.2 748.3 749.2 782.1 9708.2 9845.5 9881.0 9913.1 9924.1 641.1 684.3 702.8 716.2 761.5 4466.0 4540.8 4555.2 4568.4 4686.0 70793.3 71859.6 74685.0 74771.3 74807.6 61621.4 61640.7 62944.7 64566.0 64695.7 56474.0 57144.0 57594.0 57736.7 58888.1 8719.1 8809.4 8820.5 8839.6 8860.5 37162.3 37188.4 37454.4 37580.4 38114.0 49548.8 50133.0 50322.7 50448.7 51387.0 11154.2 11560.1 11835.2 12011.8 12124.3 11667.6 11813.7 12245.3 12761.6 12867.5 11608.5 11635.9 11893.3 12117.5 12236.6 11817.5 11909.2 11934.0 12121.6 12549.7 11044.7 11477.0 11913.3 12279.8 12525.4 9835.7 9943.1 10042.0 10082.7 10278.8 9334.5 9442.4 9487.7 9488.3 9619.8 23:30:35 INFO - PROCESS | 24593 | -------- Summary: end -------- 23:30:35 INFO - PROCESS | 24593 | 23:30:36 INFO - TEST-INFO | 24593: exit 0 23:30:36 INFO - TEST-OK | dromaeo_css | took 1112777ms 23:30:36 INFO - TEST-START | kraken 23:30:36 INFO - operating with platform_type : linux_ 23:30:36 INFO - Initialising browser for kraken test... 23:30:36 INFO - TEST-INFO | started process 24772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiPzM_M/profile http://localhost:57072/getInfo.html) 23:30:40 INFO - PROCESS | 24772 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 23:30:40 INFO - PROCESS | 24772 | __metrics Screen width/height:1600/1200 23:30:40 INFO - PROCESS | 24772 | colorDepth:24 23:30:40 INFO - PROCESS | 24772 | Browser inner width/height: 1024/697 23:30:40 INFO - PROCESS | 24772 | __metrics 23:30:41 ERROR - PROCESS | 24772 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:30:42 INFO - TEST-INFO | 24772: exit 0 23:30:42 INFO - Browser initialized. 23:30:42 INFO - Running cycle 1/1 for kraken test... 23:30:42 INFO - TEST-INFO | started process 24857 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiPzM_M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:30:54 INFO - PROCESS | 24857 | RSS: Main: 152633344 23:30:54 INFO - PROCESS | 24857 | 23:30:56 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57072/tests/kraken/driver.html?testName=audio-beat-detection) 23:30:57 INFO - PROCESS | 24857 | RSS: Main: 208900096 23:30:57 INFO - PROCESS | 24857 | 23:31:00 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57072/tests/kraken/driver.html?testName=audio-dft) 23:31:01 INFO - PROCESS | 24857 | RSS: Main: 197726208 23:31:01 INFO - PROCESS | 24857 | 23:31:04 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57072/tests/kraken/driver.html?testName=audio-fft) 23:31:04 INFO - PROCESS | 24857 | RSS: Main: 265465856 23:31:04 INFO - PROCESS | 24857 | 23:31:07 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57072/tests/kraken/driver.html?testName=audio-oscillator) 23:31:07 INFO - PROCESS | 24857 | RSS: Main: 174743552 23:31:07 INFO - PROCESS | 24857 | 23:31:10 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57072/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:31:10 INFO - PROCESS | 24857 | RSS: Main: 178671616 23:31:10 INFO - PROCESS | 24857 | 23:31:13 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57072/tests/kraken/driver.html?testName=imaging-darkroom) 23:31:14 INFO - PROCESS | 24857 | RSS: Main: 214704128 23:31:14 INFO - PROCESS | 24857 | 23:31:17 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57072/tests/kraken/driver.html?testName=imaging-desaturate) 23:31:18 INFO - PROCESS | 24857 | RSS: Main: 212590592 23:31:18 INFO - PROCESS | 24857 | 23:31:21 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57072/tests/kraken/driver.html?testName=json-parse-financial) 23:31:22 INFO - PROCESS | 24857 | RSS: Main: 213725184 23:31:22 INFO - PROCESS | 24857 | 23:31:23 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57072/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:31:24 INFO - PROCESS | 24857 | RSS: Main: 205852672 23:31:24 INFO - PROCESS | 24857 | 23:31:26 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:31:26 INFO - PROCESS | 24857 | RSS: Main: 208359424 23:31:26 INFO - PROCESS | 24857 | 23:31:28 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:31:29 INFO - PROCESS | 24857 | RSS: Main: 216821760 23:31:29 INFO - PROCESS | 24857 | 23:31:32 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:31:32 INFO - PROCESS | 24857 | RSS: Main: 222535680 23:31:32 INFO - PROCESS | 24857 | 23:31:35 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:31:36 INFO - PROCESS | 24857 | RSS: Main: 217710592 23:31:36 INFO - PROCESS | 24857 | 23:31:37 INFO - PROCESS | 24857 | Cycle 1(1): loaded http://localhost:57072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57072/tests/kraken/driver.html?testName=ai-astar) 23:31:38 INFO - PROCESS | 24857 | RSS: Main: 262889472 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | __start_tp_report 23:31:38 INFO - PROCESS | 24857 | _x_x_mozilla_page_load 23:31:38 INFO - PROCESS | 24857 | _x_x_mozilla_page_load_details 23:31:38 INFO - PROCESS | 24857 | |i|pagename|runs| 23:31:38 INFO - PROCESS | 24857 | |0;ai-astar;110;106;112;111;111;110;110;111;110;110 23:31:38 INFO - PROCESS | 24857 | |1;audio-beat-detection;148;123;155;214;132;157;157;127;184;124 23:31:38 INFO - PROCESS | 24857 | |2;audio-dft;161;164;162;161;163;161;161;161;161;162 23:31:38 INFO - PROCESS | 24857 | |3;audio-fft;76;77;76;154;75;76;75;74;132;75 23:31:38 INFO - PROCESS | 24857 | |4;audio-oscillator;92;92;143;94;92;92;125;91;91;117 23:31:38 INFO - PROCESS | 24857 | |5;imaging-gaussian-blur;116;118;117;117;116;117;117;116;117;116 23:31:38 INFO - PROCESS | 24857 | |6;imaging-darkroom;145;145;144;145;146;146;145;146;145;146 23:31:38 INFO - PROCESS | 24857 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;95;95 23:31:38 INFO - PROCESS | 24857 | |8;json-parse-financial;55;55;56;56;55;54;58;107;55;53 23:31:38 INFO - PROCESS | 24857 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;60;60;61 23:31:38 INFO - PROCESS | 24857 | |10;stanford-crypto-aes;82;81;78;77;78;80;79;79;79;79 23:31:38 INFO - PROCESS | 24857 | |11;stanford-crypto-ccm;122;123;120;120;119;120;125;118;199;122 23:31:38 INFO - PROCESS | 24857 | |12;stanford-crypto-pbkdf2;163;165;161;160;167;166;162;162;159;161 23:31:38 INFO - PROCESS | 24857 | |13;stanford-crypto-sha256-iterative;63;66;63;64;66;63;62;61;61;62 23:31:38 INFO - PROCESS | 24857 | __end_tp_report 23:31:38 INFO - PROCESS | 24857 | __start_cc_report 23:31:38 INFO - PROCESS | 24857 | _x_x_mozilla_cycle_collect,1819 23:31:38 INFO - PROCESS | 24857 | __end_cc_report 23:31:38 INFO - PROCESS | 24857 | __startTimestamp1463207498170__endTimestamp 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | ------- Summary: start ------- 23:31:38 INFO - PROCESS | 24857 | Number of tests: 14 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:1.60 (1.4%) stddev-sans-first:1.69 23:31:38 INFO - PROCESS | 24857 | Values: 110.0 106.0 112.0 111.0 111.0 110.0 110.0 111.0 110.0 110.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#1] audio-beat-detection Cycles:10 Average:152.10 Median:156.00 stddev:29.05 (18.6%) stddev-sans-first:30.77 23:31:38 INFO - PROCESS | 24857 | Values: 148.0 123.0 155.0 214.0 132.0 157.0 157.0 127.0 184.0 124.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.50 stddev:1.06 (0.7%) stddev-sans-first:1.09 23:31:38 INFO - PROCESS | 24857 | Values: 161.0 164.0 162.0 161.0 163.0 161.0 161.0 161.0 161.0 162.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#3] audio-fft Cycles:10 Average:89.00 Median:75.50 stddev:28.94 (38.3%) stddev-sans-first:30.31 23:31:38 INFO - PROCESS | 24857 | Values: 76.0 77.0 76.0 154.0 75.0 76.0 75.0 74.0 132.0 75.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:92.00 stddev:18.66 (20.3%) stddev-sans-first:19.37 23:31:38 INFO - PROCESS | 24857 | Values: 92.0 92.0 143.0 94.0 92.0 92.0 125.0 91.0 91.0 117.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 23:31:38 INFO - PROCESS | 24857 | Values: 116.0 118.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 23:31:38 INFO - PROCESS | 24857 | Values: 145.0 145.0 144.0 145.0 146.0 146.0 145.0 146.0 145.0 146.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:31:38 INFO - PROCESS | 24857 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:55.00 stddev:16.43 (29.9%) stddev-sans-first:17.31 23:31:38 INFO - PROCESS | 24857 | Values: 55.0 55.0 56.0 56.0 55.0 54.0 58.0 107.0 55.0 53.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 23:31:38 INFO - PROCESS | 24857 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:1.48 (1.9%) stddev-sans-first:1.17 23:31:38 INFO - PROCESS | 24857 | Values: 82.0 81.0 78.0 77.0 78.0 80.0 79.0 79.0 79.0 79.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:122.00 stddev:24.75 (20.3%) stddev-sans-first:26.13 23:31:38 INFO - PROCESS | 24857 | Values: 122.0 123.0 120.0 120.0 119.0 120.0 125.0 118.0 199.0 122.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:162.50 stddev:2.63 (1.6%) stddev-sans-first:2.79 23:31:38 INFO - PROCESS | 24857 | Values: 163.0 165.0 161.0 160.0 167.0 166.0 162.0 162.0 159.0 161.0 23:31:38 INFO - PROCESS | 24857 | 23:31:38 INFO - PROCESS | 24857 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.79 (2.8%) stddev-sans-first:1.90 23:31:38 INFO - PROCESS | 24857 | Values: 63.0 66.0 63.0 64.0 66.0 63.0 62.0 61.0 61.0 62.0 23:31:38 INFO - PROCESS | 24857 | -------- Summary: end -------- 23:31:38 INFO - PROCESS | 24857 | 23:31:39 INFO - TEST-INFO | 24857: exit 0 23:31:39 INFO - TEST-OK | kraken | took 62780ms 23:31:39 INFO - SUITE-END | took 1175s 23:31:39 INFO - Completed test suite (00:19:35) 23:31:39 INFO - javascript benchmark 23:31:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3089.2276930767307, 3105.052958847552, 3125.32805446829, 3135.7804953653254, 3157.636871759747, 5173.689578625281, 5275.736213189344, 5298.708571028592, 5334.439883812202, 5351.197320401937, 5342.492463215998, 5363.544098751601, 5367.731613419333, 5427.538659213971, 5460.918086228699, 4813.302071199682, 4897.853064408074, 4917.631177661672, 4921.089598424285, 5033.723145227011, 4945.480724523927, 4975.27858460523, 4998.625103117262, 5018.899622007558, 5019.322391477141, 4744.4306683198065, 4760.095581839457, 4780.713157210578, 4784.354112194844, 4788.760561971888, 4786.880327991793, 4909.509049095098, 4910.410750709938, 4918.286848406961, 4953.0, 4743.976280118612, 4780.49804770501, 4864.075825593126, 4882.584980276645, 4927.728974906415, 5262.473752624745, 5304.575633949314, 5320.893582128375, 5345.412004679482, 5351.598630102758, 5712.914306285409, 5752.194692743019, 5775.5957083004205, 5780.624259423124, 5784.508316793078, 5600.131979543177, 5665.320161580596, 5701.657900525982, 5775.711214439261, 5839.824805255849, 5388.030154572176, 5418.729063546807, 5430.02259593277, 5437.619366644337, 5438.157085651691, 5916.911246331309, 5933.436323549256, 5935.0, 5937.0, 5939.940600594025, 5549.306336707911, 5573.6098473106495, 5583.748731307101, 5615.634983726045, 5688.431156884279, 5731.512821410143, 5743.023685973395, 5744.712764361765, 5744.885102298014, 5751.798687046017, 5704.486596206279, 5708.0, 5708.0581704019205, 5711.343195532567, 5717.342505611908, 5657.094864821609, 5664.178694089334, 5667.971660141673, 5713.628614140068, 5743.167240750151, 4364.301711726109, 4367.879135399292, 4375.518692943773, 4376.715513491613, 4405.581469760367, 3840.0399900024995, 3887.6695480883877, 3897.5517815451017, 3908.101136738509, 3953.8418463261146, 5374.677519348852, 5386.165144402624, 5413.133898576209, 5456.508914197741, 5459.2902922619805, 5743.2246646703, 5746.712664366765, 5771.163181338682, 5771.769129234867, 5789.160571717078, 5318.335208098988, 5387.515123638891, 5422.322209723785, 5431.1853222016225, 5444.727763611803, 4793.712377257376, 4813.518648135228, 4835.395575553056, 4855.562999330077, 4860.73265970368, 6004.969975150097, 6012.879742405127, 6022.759089636453, 6029.638221706711, 6040.335563088057, 5658.434156584309, 5698.6580805151825, 5710.828675139753, 5754.338251101177, 5832.533397328203, 5953.732082056231, 5957.851053723692, 5960.791372301948, 5962.284525856925, 5966.284045914518, 5663.716814159358, 5665.943340566541, 5669.659820410789, 5679.148127780866, 5681.545476361798, 5571.749271282721, 5740.712964351849, 5756.913646295226, 5783.306003279633, 5858.384869588632, 5701.771929122789, 5705.0, 5705.372409035086, 5705.429457054427, 5714.514266287412, 5394.730263486731, 5636.0, 5669.177969194526, 5694.772209111589, 5702.401247868914, 5420.674759514442, 5460.399356070909, 5492.286002819608, 5532.75102620375, 5557.083081291542, 5663.603547751618, 5667.971660141673, 5681.715914204191, 5716.5426765859465, 5718.771249150153, 5389.353277606712, 5506.421825708403, 5534.861628459321, 5542.0855558832345, 5576.972115139399], "value": 5289.9844177069735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.3008774865029, 634.2674211285965, 645.5610185074149, 652.3378770547895, 666.1839246922517, 31718.36563268736, 43220.56779432209, 43565.78217108914, 43594.0, 47160.0, 44670.0, 45403.545964540346, 45436.3184552232, 45443.77278113613, 45455.31817022739, 8856.512891790933, 9073.228775554086, 9079.046700096495, 9084.137006984325, 9084.273258139367, 8986.730398088068, 9100.726978190633, 9109.68116115936, 9113.225375843062, 9114.680986165486, 9152.542372881362, 9188.356815022951, 9191.126842949909, 9193.126652967958, 9193.35646504745, 9088.681896133636, 9134.954325228398, 9146.359754817166, 9152.633894644207, 9154.771130721718, 8982.685606003824, 9044.457332560001, 9046.502442365667, 9054.637814487412, 9058.411203271766, 42716.0, 43195.0, 43207.351889721365, 43217.0, 43225.35161972573, 3731.8320675569666, 3751.3060083884297, 3852.152526444178, 3858.6913046956174, 3898.9805050974846, 32567.837160814044, 32722.113075747664, 32845.01464956055, 32859.0, 33881.66118338834, 4316.58992395725, 4326.351047342892, 4332.133573285356, 4371.540988196225, 4420.447444069491, 240873.0, 241151.0, 338527.0, 338919.0, 341388.0, 50540.49459505358, 56363.0, 57164.71417642885, 57164.71417642885, 57225.427745722845, 59189.0, 59766.103508447406, 59823.700881495315, 66574.66712666406, 66639.0, 16990.57523561921, 17154.828451715573, 17164.82835171632, 17196.0, 17202.569935751457, 12700.682482937817, 13001.934990324988, 13641.931790340985, 13813.102148360324, 13836.654083647789, 15942.442014529435, 16191.433299834449, 16327.673446531004, 16763.832361676472, 16771.664566708598, 6372.4583410409705, 6448.613083214928, 6600.60396376219, 6607.834804129935, 6667.433268172163, 1015.7054454208345, 1145.2907257322242, 1164.4673924354236, 1177.8115501519715, 1223.804191329383, 32701.345973080406, 33303.50044749331, 33320.333593327996, 33364.0, 33687.66312336894, 1117.94969226385, 1165.5221359242669, 1167.6380322100176, 1209.1535924852637, 1258.704204511939, 17064.48806535806, 17635.647287054187, 17654.823451765576, 17705.731931629773, 17775.377861774778, 501885.0, 509357.0, 511179.0, 511272.0, 512299.0, 57265.713671431375, 58100.4189958095, 58124.0, 58155.41844581585, 58243.0, 1303.3743802974436, 1392.5822253323963, 1395.114102544871, 1426.985730142706, 1441.2001339256813, 32578.674213257564, 34567.30865382628, 35436.822815886786, 35479.11302217465, 36787.63212367949, 57375.71312143413, 61979.0, 63907.68046159925, 63925.0, 68639.65680171567, 481958.0, 490715.0, 493239.0, 493284.0, 493963.0, 40461.0, 47700.522994770996, 51123.23315150201, 51177.74411127921, 52312.47687523076, 48678.0, 52561.0, 53154.46845531495, 53945.46054539561, 54260.45739542554, 43872.34191487067, 43979.560204398826, 44455.33317000183, 44695.776521117186, 45703.31445028261, 43939.0, 44674.77662611666, 45163.54836451593, 45191.77404112958, 45350.77324613488, 44596.77701611471, 45331.32003020023, 45442.77278613586, 45620.54379456296, 45667.314990274506, 36668.81665591655, 38855.222895542494, 39317.2136557273, 39914.2017159661, 40463.595364045985, 29419.41161176807, 29634.40731185321, 29869.850650746608, 30224.0, 30334.69665303407], "value": 21056.683993961033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.1532297498232, 407.4194273160745, 409.54540460089305, 415.4246368779242, 417.40102952263504, 13978.860211397909, 18368.448946531593, 19863.30478433255, 19872.60254794903, 19881.0, 15537.611559711013, 19031.904840475778, 19104.617907641838, 19315.806841931575, 20141.590088693796, 5671.8014869479475, 5902.940970590304, 5903.527717782588, 5909.438603332676, 5919.319278282988, 5726.599138060337, 5808.1287806829105, 5812.505936995361, 5814.156947242647, 5821.738021789009, 4108.589141085898, 6106.542009349294, 6423.4861211103, 6433.613983161002, 6547.345265473462, 6386.840328991812, 6475.83810404739, 6478.449331806802, 6493.512986526006, 6523.641199734013, 5631.9718401408145, 5653.547716182696, 5692.9146062809095, 5753.482186603226, 5769.394213607553, 20148.39554813358, 23677.644835327483, 23780.55181998618, 23898.402539936465, 24299.878500607385, 2383.499465112311, 2393.042782886842, 2399.256230568512, 2997.3405850712807, 3012.4276387486307, 14285.57143285703, 14782.334794934253, 14847.851521484863, 14903.88220883438, 15204.695906081926, 1979.495228716668, 2038.398672391648, 2047.6518991771434, 2104.8210902073342, 2173.5435558532727, 81809.00100988886, 82265.58867205626, 84589.57705211497, 85168.57415712943, 85221.57389213076, 10518.842217366746, 10742.409167495707, 10745.871683473279, 11116.388598627153, 11239.325640461599, 15516.069035857885, 15610.063396196265, 15698.0, 15698.529044128694, 15728.213589320489, 8390.664373424994, 8706.259967902672, 8740.562971851383, 8878.73363799087, 8881.067487913806, 8646.610902509403, 8697.912760904886, 8789.252913502296, 8854.955725221462, 8899.199072083415, 5978.940210597925, 6598.538102332886, 6678.298778628174, 6809.659517024129, 6937.68780404883, 1828.9634207316046, 1941.4952112450865, 1993.700944858254, 2010.819026287612, 2028.766691830429, 982.8525721141743, 1019.3679918450608, 1106.2145876427664, 1110.9723505757142, 1132.7621199548023, 20428.182872684927, 21452.89273553622, 21707.782922170893, 21793.128274869145, 22022.669659955114, 1005.9295849290626, 1071.164491696478, 1113.4599719136213, 1113.8106521891298, 1125.6341688951125, 11703.122265830098, 12176.269423834598, 12272.877271227351, 12344.62966111018, 12403.759624037524, 453070.0, 455585.0, 466091.0, 467913.0, 469390.0, 87896.56051719701, 94606.0, 95131.0, 95207.52396237974, 95362.52318738501, 1073.4793625091825, 1080.1682704317789, 1119.5409881948362, 1129.0910816792561, 1136.7044568412107, 17569.209385577935, 17796.644067118843, 17823.287068517115, 18032.368867090114, 18105.456836294932, 30442.84778576093, 36827.447588286734, 39503.60496394999, 40478.595214047484, 40922.18155636811, 53349.73325133349, 54106.45893541014, 54691.72654136704, 55110.6867951544, 56384.0, 7741.496802707806, 8622.396432249932, 8839.823203536022, 9026.68406605792, 9289.6284148636, 8472.957635211784, 12728.045396595478, 12926.353682316034, 13341.466341346237, 13756.312184390541, 11120.944395277971, 11248.831267531157, 11271.943640282074, 11322.94338528302, 11570.132240082195, 6556.967215163893, 9883.703488895344, 11189.104871610414, 11344.262622929484, 12030.819537706766, 11546.711332216762, 11788.29270243788, 12085.335306558223, 12338.3830808461, 12369.443375048324, 8749.8687519686, 8870.201681848665, 8976.41653292534, 9090.090990900857, 9317.922162707657, 11737.178397512092, 11936.28382297065, 12069.094817888517, 12105.213161144497, 12146.817797732865], "value": 9609.18031724152, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.083445760255, 1532.7547592385217, 1571.9685606287874, 1747.7553142560037, 1857.322077441734, 7840.33357164641, 8537.615807288672, 8774.824503509933, 8806.251468625174, 8919.866202006975, 8757.518336491506, 8934.419262747933, 8998.730038098867, 9005.369624126313, 9009.774755631097, 2366.230974933145, 2392.7009123859516, 2393.258089992108, 2395.161693407302, 2395.6047252203366, 2323.1984965186984, 2349.3304408243653, 2365.7161140663143, 2366.715994080713, 2372.9881350593223, 2344.6248600223967, 2350.894209760565, 2360.84654497458, 2364.13708996216, 2385.2009576791797, 2296.6555016747457, 2319.82601304902, 2323.9767602323927, 2325.7790509901615, 2332.0788288626068, 2276.0781883337277, 2278.362058623588, 2280.5438912217624, 2281.646344816556, 2283.041122728458, 8762.780930476725, 8912.955435222848, 8963.596638151299, 8969.820603587958, 9010.774730631787, 5429.891402171935, 5553.0837411827, 5559.19391688207, 5562.749676264578, 5563.415841336642, 3557.6442355764475, 3567.0547304964207, 3622.9275414491826, 3701.777893326409, 3718.7954662493553, 1238.8017917133307, 1278.181963543333, 2190.113004233288, 2200.460887082659, 2201.5816994770944, 11993.100517461138, 12375.948044416235, 12443.0, 12465.501379944792, 12482.563110291187, 4510.887227819265, 4580.152671755736, 4638.907221855544, 4644.86065418038, 4660.464046634613, 9938.900610993798, 9982.201423886072, 9995.900040999497, 10000.899991000142, 10009.149222316039, 5327.334083239595, 5329.680219186861, 5330.067238233371, 5330.253764472977, 5332.546733527616, 4435.489918659331, 4552.635789136861, 4900.828471003498, 4998.975005124951, 5066.923996140061, 3940.133170702441, 3944.6449819516383, 3988.80055997199, 4030.1133750574827, 4059.573744756817, 1208.4501551793912, 1211.4790640024873, 1239.4360565942475, 1241.205628397826, 1257.076049103908, 750.6772088002211, 904.1997831918799, 971.140540621555, 981.1954197558076, 1012.0739523336213, 5876.382979787061, 5964.522838172936, 6059.8485037873525, 6069.423404776539, 6393.712282947278, 983.5770618633914, 991.1723710701644, 1034.912032477248, 1037.1961729659574, 1064.8136576099153, 3991.3015222335976, 4087.8773636790943, 4146.087860670647, 4149.232392007489, 4175.665946724254, 11126.721831954104, 11447.885521144683, 11516.481758320728, 11521.654350369334, 11522.0, 11406.0, 11705.531778728926, 11720.531178752924, 11820.70448238801, 11823.467943942544, 8165.877511837327, 8404.663813447394, 8489.278411335106, 8492.957535212285, 8599.742007739778, 6020.819375418744, 6028.57799953999, 6051.788187413419, 6182.072689096672, 6220.440160385587, 10154.238432117769, 10502.579896804042, 10714.250002500063, 10732.946335268274, 10741.301815381976, 10525.526351314362, 10800.405977671304, 10801.621943231949, 10810.0, 10884.346939183675, 7172.713091476491, 7452.254774522505, 7663.540187588763, 7803.063632364153, 7931.960340198262, 8498.0, 8515.914840851512, 8551.657933682583, 8597.914020859962, 8600.956995214983, 7614.086309642878, 7753.573553454614, 7789.649465774167, 7899.802504937423, 7920.801979950547, 7392.371648409945, 7453.81365465846, 7465.6267186641535, 7604.2395760423515, 7613.9238607613215, 6921.204061532988, 6963.059986901703, 6985.5808651481075, 7002.964985175042, 7013.263607321362, 4846.975765121152, 5033.723145227048, 5128.512791284822, 5131.820386286462, 5138.511841375063, 4982.277569752293, 5048.293238946624, 5052.9494705052475, 5063.898722025465, 5168.9741551292], "value": 4764.574759870143, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.7798380125812, 323.97894136881104, 333.0707326558901, 338.6900985598178, 340.67125224158684, 5329.999999999995, 5383.43473935236, 5384.6769193848395, 5401.081816091264, 5424.945750542493, 5077.492250774921, 5087.186050231963, 5196.740162991845, 5263.894722105565, 5335.679859208451, 4078.8164532595956, 4087.3460246360587, 4113.614103819792, 4167.5832416758385, 4175.812088456019, 4103.692223083266, 4111.691623128262, 4137.710360274782, 4149.232392007473, 4152.774931395231, 3954.1300913799064, 4074.7351422157467, 4129.194807012634, 4163.250614889319, 4171.687123465752, 4126.442930204414, 4143.461350024508, 4160.188763191179, 4162.146759914211, 4170.937435938464, 3682.871099511531, 3689.6679298863232, 3711.740178187528, 3711.832967516468, 3715.8327875245677, 4981.452040275567, 5096.0, 5105.208692652608, 5118.6416950813455, 5151.562117220041, 4743.549362810528, 4760.095581839474, 4779.641526885463, 4797.208460603996, 4802.087603355351, 5544.86330302287, 5545.417731138213, 5545.750441230154, 5557.3886872444, 5566.248556444869, 3877.418387241908, 4008.6993475489453, 4018.839246430139, 4027.254957832781, 4058.9797051014557, 5684.744186511617, 5712.80005199816, 5746.0, 5767.6539407635255, 5782.421757824226, 4075.857344992911, 4167.35373038123, 4169.624733773975, 4171.520275168334, 4174.144300418407, 3883.825227864753, 4073.4704488416314, 4079.0, 4110.314770943899, 4146.673064619293, 4649.8840278332955, 4664.134346261522, 4671.696339737906, 4685.765711714401, 4687.906241875144, 5498.890022199534, 5512.503874651301, 5557.5553955683445, 5581.190727344512, 5652.406497317805, 1374.8693874081946, 1406.3741634972544, 1421.8720315171477, 1424.0672359604475, 1436.6552027513321, 907.3648446087633, 907.3875134284332, 916.6699988004314, 917.4449458077901, 920.093707697917, 4779.0, 4800.711957282574, 4808.783604737795, 4820.421549414093, 4843.491433399442, 4995.775190116454, 5050.267711181858, 5051.419086805065, 5067.78373190439, 5072.746362681851, 4482.7758612069265, 4761.214399624023, 4809.254565542346, 4817.8313759018265, 4847.200211965057, 2695.0, 2714.267147870064, 2714.4978179036943, 2714.606382074588, 2719.966412763177, 5128.358955130609, 5132.458603563529, 5196.662216955961, 5217.895642087137, 5252.579793616502, 4808.831690890802, 5036.722980236123, 5050.570701490341, 5098.97450512745, 5108.1865937592365, 5154.30416893722, 5190.844274671766, 5223.895522089537, 5264.499872512105, 5301.416844147141, 5019.7490125494305, 5042.697438153723, 5081.618878584195, 5125.641205115755, 5129.512696293846, 4777.904441911072, 5021.774020169174, 5023.799048038184, 5066.544011039025, 5077.7461126944245, 5287.524122828964, 5295.27903431394, 5303.178007408857, 5305.495977882095, 5310.787568497217, 3674.4855720198657, 3682.944755828611, 3687.6128006559998, 3832.3485007548225, 3937.7637341759587, 5602.831915042555, 5665.858353541194, 5715.542756579381, 5729.770809167753, 5757.481826635623, 5575.135853942646, 5575.944240557543, 5580.860478487908, 5600.775968961196, 5603.495685388335, 5297.364316281916, 5519.151084386775, 5534.391216966211, 5538.529225015919, 5563.610547261653, 5403.675779453245, 5438.809641662523, 5439.857629897766, 5440.0959664117245, 5455.754491047833, 5398.40617532079, 5518.641288316247, 5528.391876893458, 5568.192612071146, 5619.6512836919665, 5404.78380864761, 5441.455854414527, 5442.918356224581, 5449.591280653887, 5470.398256191736], "value": 4091.946815921713, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7993661616287, 814.4543156085423, 842.4271495383141, 849.0999540487088, 871.1158174452931, 14877.553673389788, 15075.0, 15326.310316035777, 15460.613484662888, 15472.226388680578, 13628.250446225453, 14023.228722420263, 14035.298235088256, 14043.36804843782, 14170.574882753534, 701.4003027411566, 817.0930267403178, 822.8148666550009, 826.5990994367727, 840.6931470013462, 821.2772759971217, 823.9217274358926, 827.9213474719892, 828.1015098618007, 830.7756905635475, 723.1936390924108, 891.4517571693427, 891.5185799668204, 891.7413949954505, 893.9955300223489, 733.7578599062297, 738.114262884538, 864.0883867519764, 874.6938571499987, 874.7725591346232, 646.0406296649472, 648.627039452312, 651.2217899609968, 651.3974573519523, 651.5569412799294, 14275.21486318252, 14338.928305358406, 14379.568612941628, 14380.137191768423, 14380.137191768526, 12949.223046617139, 13496.730065398637, 13938.93030534851, 13946.511872084428, 14048.789268160986, 12040.879591204064, 12057.819132713017, 12373.443195056245, 12500.562480313145, 12764.80852787209, 4366.235908715994, 4463.553644635511, 4475.865724028285, 4491.977540112279, 4578.885527861797, 58509.122363164584, 58516.122258166164, 58830.11754823681, 58957.705211473665, 60886.695566521885, 10791.676249712522, 10977.176711746491, 11011.174161937886, 11112.05547528453, 11501.539938402537, 20616.0, 20616.58766824655, 20639.8968005159, 20642.0, 20665.0, 7877.291043806085, 8008.079070906921, 8067.556284404297, 8300.0, 8329.458585191944, 4633.582977531971, 4648.9075067359145, 4697.060587882369, 4828.251620998751, 4902.681325713817, 3334.866605335808, 3433.5193072969805, 3447.413939630269, 3453.6028356738793, 3456.0495863637198, 617.9845503862349, 621.7326549583722, 630.0328994992756, 632.0550776589029, 646.0599827251338, 658.1904257762883, 694.2086021934998, 748.3489364253135, 749.2208103572333, 782.1005843280274, 9708.223342132613, 9845.45849978258, 9881.0, 9913.107820296207, 9924.106830385155, 641.1088586864263, 684.3327755438507, 702.8278071872322, 716.2300526933567, 761.466973118819, 4465.955340446619, 4540.818367265272, 4555.2256116460285, 4568.406107206042, 4685.976570117127, 70793.29206707867, 71859.64070179721, 74685.0, 74771.25228747752, 74807.62596186984, 61621.38378616156, 61640.691796540734, 62944.68527657333, 64566.0, 64695.67652161709, 56474.0, 57143.99999999917, 57594.0, 57736.71131644399, 58888.11667824986, 8719.12808719123, 8809.383343165917, 8820.514871682119, 8839.558022098741, 8860.468371897707, 37162.256754865295, 37188.44217336796, 37454.438183426726, 37580.436293456165, 38114.0, 49548.75225623849, 50133.0, 50322.74838625784, 50448.747756260986, 51387.0, 11154.219204655921, 11560.132990025613, 11835.171537992257, 12011.819822702491, 12124.333161676192, 11667.59163429276, 11813.704657383634, 12245.265284082983, 12761.553345632858, 12867.549635762703, 11608.535658573563, 11635.941820290844, 11893.286402815858, 12117.454714537691, 12236.571719989757, 11817.527298907948, 11909.166358354832, 11934.0, 12121.636350909486, 12549.686257843627, 11044.723881903017, 11477.0, 11913.285202887855, 12279.754404911673, 12525.373731313215, 9835.655752048644, 9943.105120539187, 10042.0, 10082.69751907444, 10278.845817312897, 9334.533273336441, 9442.433453992782, 9487.66793162236, 9488.335816493056, 9619.807603848023], "value": 6897.305896972991, "unit": "score"}], "name": "dromaeo_css", "value": 7239.391947332903, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 112.0, 111.0, 111.0, 110.0, 110.0, 111.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 155.0, 214.0, 132.0, 157.0, 157.0, 127.0, 184.0, 124.0], "value": 152.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 162.0, 161.0, 163.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 154.0, 75.0, 76.0, 75.0, 74.0, 132.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 143.0, 94.0, 92.0, 92.0, 125.0, 91.0, 91.0, 117.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 144.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 56.0, 55.0, 54.0, 58.0, 107.0, 55.0, 53.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 78.0, 77.0, 78.0, 80.0, 79.0, 79.0, 79.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 123.0, 120.0, 120.0, 119.0, 120.0, 125.0, 118.0, 199.0, 122.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 161.0, 160.0, 167.0, 166.0, 162.0, 162.0, 159.0, 161.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 63.0, 64.0, 66.0, 63.0, 62.0, 61.0, 61.0, 62.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1526.6999999999998, "alertThreshold": 2.0}]} 23:31:39 INFO - Return code: 0 23:31:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:31:39 ERROR - # TBPL SUCCESS # 23:31:39 INFO - Running post-action listener: _resource_record_post_action 23:31:39 INFO - ##### 23:31:39 INFO - ##### Finished run-tests step (success) 23:31:39 INFO - ##### 23:31:39 INFO - Running post-run listener: _resource_record_post_run 23:31:39 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 535531520; Read time: 8; Write time: 537968 23:31:39 INFO - TinderboxPrint: CPU usage
13.2% 23:31:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:31:39 INFO - TinderboxPrint: I/O write bytes / time
535,531,520 / 537,968 23:31:39 INFO - TinderboxPrint: CPU idle
8,266.3 (86.8%) 23:31:39 INFO - TinderboxPrint: CPU user
1,207.9 (12.7%) 23:31:39 INFO - TinderboxPrint: Swap in / out
0 / 0 23:31:39 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246284288; Read time: 0; Write time: 254244 23:31:39 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289247232; Read time: 8; Write time: 283724 23:31:40 INFO - Running post-run listener: _upload_blobber_files 23:31:40 INFO - Blob upload gear active. 23:31:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:31:40 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 23:31:40 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:31:40 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:31:40 INFO - (blobuploader) - INFO - Open directory for files ... 23:31:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:31:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:41 INFO - (blobuploader) - INFO - Done attempting. 23:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:45 INFO - (blobuploader) - INFO - Done attempting. 23:31:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:31:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:31:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:46 INFO - (blobuploader) - INFO - Done attempting. 23:31:46 INFO - (blobuploader) - INFO - Iteration through files over. 23:31:46 INFO - Return code: 0 23:31:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:31:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:31:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4f67eac6a9d09118f79f40c05186a4b8c871da9d977d843cd05d56f8f2b94cda43eace3137e0285c3a1c1fbf663c8ddd6f091b446081edec452e28ea44c6c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd1fe641ccdc252e0a24e9c168293de7fcf77f50d14fe6ce2bfaddcbb0d446bc8d680f89a80caef3405dd61d22f18ade81307da3c129661cb4db51162e566303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ddd86a6dd6f99caf4ae318fb4e19a5229edef8db871a4aba4f5f649989343d92041307ae7f998d24243cac791da32b2b85762a946cb0019c055a92bf2516cb"} 23:31:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:31:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:31:46 INFO - Contents: 23:31:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4f67eac6a9d09118f79f40c05186a4b8c871da9d977d843cd05d56f8f2b94cda43eace3137e0285c3a1c1fbf663c8ddd6f091b446081edec452e28ea44c6c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd1fe641ccdc252e0a24e9c168293de7fcf77f50d14fe6ce2bfaddcbb0d446bc8d680f89a80caef3405dd61d22f18ade81307da3c129661cb4db51162e566303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ddd86a6dd6f99caf4ae318fb4e19a5229edef8db871a4aba4f5f649989343d92041307ae7f998d24243cac791da32b2b85762a946cb0019c055a92bf2516cb"} 23:31:46 INFO - Running post-run listener: copy_logs_to_upload_dir 23:31:46 INFO - Copying logs to upload dir... 23:31:46 INFO - mkdir: /builds/slave/test/build/upload/logs 23:31:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.121354 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-13 23:31:45.682125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 23:31:45.689919) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4f67eac6a9d09118f79f40c05186a4b8c871da9d977d843cd05d56f8f2b94cda43eace3137e0285c3a1c1fbf663c8ddd6f091b446081edec452e28ea44c6c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd1fe641ccdc252e0a24e9c168293de7fcf77f50d14fe6ce2bfaddcbb0d446bc8d680f89a80caef3405dd61d22f18ade81307da3c129661cb4db51162e566303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ddd86a6dd6f99caf4ae318fb4e19a5229edef8db871a4aba4f5f649989343d92041307ae7f998d24243cac791da32b2b85762a946cb0019c055a92bf2516cb"} build_url:https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/R_V7VV2kRTq9DRCHuwPz_A/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/Fx-Team/sha512/ea4f67eac6a9d09118f79f40c05186a4b8c871da9d977d843cd05d56f8f2b94cda43eace3137e0285c3a1c1fbf663c8ddd6f091b446081edec452e28ea44c6c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd1fe641ccdc252e0a24e9c168293de7fcf77f50d14fe6ce2bfaddcbb0d446bc8d680f89a80caef3405dd61d22f18ade81307da3c129661cb4db51162e566303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ddd86a6dd6f99caf4ae318fb4e19a5229edef8db871a4aba4f5f649989343d92041307ae7f998d24243cac791da32b2b85762a946cb0019c055a92bf2516cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 23:31:45.717517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:31:45.717841) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1463203569.31121-1656750572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 23:31:45.798287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 23:31:45.801649) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 23:31:45.801936) ========= ========= Total master_lag: 0.49 =========