builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1463106425.05 results: success (0) buildid: 20160512163011 builduid: f1c6d8799fa7490fbc8470abb3ddddde revision: c71165b9f120b33ce68e5eef97eb8480daedeb65 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.045774) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.054603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.054960) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.076179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.076667) ========= 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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 19:27:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 149M=0s 2016-05-12 19:27:06 (149 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104031 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.193895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.194764) ========= 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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009602 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.243497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.243870) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c71165b9f120b33ce68e5eef97eb8480daedeb65 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c71165b9f120b33ce68e5eef97eb8480daedeb65 --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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 19:27:06,626 truncating revision to first 12 chars 2016-05-12 19:27:06,626 Setting DEBUG logging. 2016-05-12 19:27:06,626 attempt 1/10 2016-05-12 19:27:06,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c71165b9f120?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 19:27:07,140 unpacking tar archive at: mozilla-inbound-c71165b9f120/testing/mozharness/ program finished with exit code 0 elapsedTime=0.744372 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:05.999984) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:06.000345) ========= script_repo_revision: c71165b9f120b33ce68e5eef97eb8480daedeb65 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:06.000910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:06.001364) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 19:27:06.007887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-12 19:27:06.008349) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:27:07 INFO - MultiFileLogger online at 20160512 19:27:07 in /builds/slave/test 19:27:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:27:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:27:07 INFO - {'append_to_log': False, 19:27:07 INFO - 'base_work_dir': '/builds/slave/test', 19:27:07 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:27:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:27:07 INFO - 'branch': 'Mozilla-Inbound', 19:27:07 INFO - 'buildbot_json_path': 'buildprops.json', 19:27:07 INFO - 'config_files': ('talos/linux_config.py',), 19:27:07 INFO - 'default_actions': ('clobber', 19:27:07 INFO - 'read-buildbot-config', 19:27:07 INFO - 'download-and-extract', 19:27:07 INFO - 'populate-webroot', 19:27:07 INFO - 'create-virtualenv', 19:27:07 INFO - 'install', 19:27:07 INFO - 'run-tests'), 19:27:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:27:07 INFO - 'download_minidump_stackwalk': True, 19:27:07 INFO - 'download_symbols': 'ondemand', 19:27:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:27:07 INFO - 'tooltool.py': '/tools/tooltool.py', 19:27:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:27:07 INFO - '/tools/misc-python/virtualenv.py')}, 19:27:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:27:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:27:07 INFO - 'installer_path': 'installer.exe', 19:27:07 INFO - 'log_level': 'info', 19:27:07 INFO - 'log_name': 'talos', 19:27:07 INFO - 'log_to_console': True, 19:27:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:27:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:27:07 INFO - 'opt_config_files': (), 19:27:07 INFO - 'pip_index': False, 19:27:07 INFO - 'sps_profile': False, 19:27:07 INFO - 'sps_profile_interval': 0, 19:27:07 INFO - 'suite': 'dromaeojs', 19:27:07 INFO - 'system_bits': '32', 19:27:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:27:07 INFO - 'title': 'talos-linux64-ix-061', 19:27:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:27:07 INFO - 'use_talos_json': True, 19:27:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:27:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:27:07 INFO - 'work_dir': 'build'} 19:27:07 INFO - ##### 19:27:07 INFO - ##### Running clobber step. 19:27:07 INFO - ##### 19:27:07 INFO - Running pre-action listener: _resource_record_pre_action 19:27:07 INFO - Running main action method: clobber 19:27:07 INFO - rmtree: /builds/slave/test/build 19:27:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:27:08 INFO - Running post-action listener: _resource_record_post_action 19:27:08 INFO - ##### 19:27:08 INFO - ##### Finished clobber step (success) 19:27:08 INFO - ##### 19:27:08 INFO - ##### 19:27:08 INFO - ##### Running read-buildbot-config step. 19:27:08 INFO - ##### 19:27:08 INFO - Running pre-action listener: _resource_record_pre_action 19:27:08 INFO - Running main action method: read_buildbot_config 19:27:08 INFO - Using buildbot properties: 19:27:08 INFO - { 19:27:08 INFO - "project": "", 19:27:08 INFO - "product": "firefox", 19:27:08 INFO - "script_repo_revision": "production", 19:27:08 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:27:08 INFO - "repository": "", 19:27:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:27:08 INFO - "buildid": "20160512163011", 19:27:08 INFO - "pgo_build": "True", 19:27:08 INFO - "basedir": "/builds/slave/test", 19:27:08 INFO - "buildnumber": 905, 19:27:08 INFO - "slavename": "talos-linux64-ix-061", 19:27:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:27:08 INFO - "platform": "ubuntu64_hw", 19:27:08 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:27:08 INFO - "branch": "mozilla-inbound", 19:27:08 INFO - "repo_path": "integration/mozilla-inbound", 19:27:08 INFO - "revision": "c71165b9f120b33ce68e5eef97eb8480daedeb65", 19:27:08 INFO - "stage_platform": "linux64-pgo", 19:27:08 INFO - "builduid": "f1c6d8799fa7490fbc8470abb3ddddde", 19:27:08 INFO - "slavebuilddir": "test" 19:27:08 INFO - } 19:27:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:27:08 INFO - Running post-action listener: _resource_record_post_action 19:27:08 INFO - ##### 19:27:08 INFO - ##### Finished read-buildbot-config step (success) 19:27:08 INFO - ##### 19:27:08 INFO - ##### 19:27:08 INFO - ##### Running download-and-extract step. 19:27:08 INFO - ##### 19:27:08 INFO - Running pre-action listener: _resource_record_pre_action 19:27:08 INFO - Running main action method: download_and_extract 19:27:08 INFO - mkdir: /builds/slave/test/build/tests 19:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:08 INFO - https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:27:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:27:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:27:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:09 INFO - Downloaded 1361 bytes. 19:27:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:27:09 INFO - Using the following test package requirements: 19:27:09 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:27:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:27:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:27:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'jsshell-linux-x86_64.zip'], 19:27:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:27:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:27:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:27:09 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:27:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:27:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:27:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:27:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:09 INFO - https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:27:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:27:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:27:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:10 INFO - Downloaded 23910240 bytes. 19:27:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:27:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:27:11 INFO - Return code: 0 19:27:11 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:27: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')]} 19:27:11 INFO - https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:27:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:27:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:27:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:13 INFO - Downloaded 23910240 bytes. 19:27: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'] 19:27: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 19:27:14 INFO - Return code: 0 19:27: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')]} 19:27:14 INFO - https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:27:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:27:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:27:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:16 INFO - Downloaded 10840239 bytes. 19:27:16 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'] 19:27:16 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 19:27:16 INFO - Return code: 0 19:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:16 INFO - https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:27:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:27:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:27:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:18 INFO - Downloaded 58416740 bytes. 19:27:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:27:18 INFO - mkdir: /builds/slave/test/properties 19:27:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:27:18 INFO - Writing to file /builds/slave/test/properties/build_url 19:27:18 INFO - Contents: 19:27:18 INFO - build_url:https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:27:19 INFO - Running post-action listener: _resource_record_post_action 19:27:19 INFO - Running post-action listener: set_extra_try_arguments 19:27:19 INFO - ##### 19:27:19 INFO - ##### Finished download-and-extract step (success) 19:27:19 INFO - ##### 19:27:19 INFO - ##### 19:27:19 INFO - ##### Running populate-webroot step. 19:27:19 INFO - ##### 19:27:19 INFO - Running pre-action listener: _resource_record_pre_action 19:27:19 INFO - Running main action method: populate_webroot 19:27:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:27:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:27:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:27:19 INFO - '--tppagecycles', 19:27:19 INFO - '7'], 19:27:19 INFO - 'tests': ['tsvgm']}}, 19:27:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:27:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:27:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:27:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:27:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['damp', 'tps']}, 19:27:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'tests': ['damp', 'tps']}, 19:27:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['dromaeo_dom']}, 19:27:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:27:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'other-e10s': {'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['a11yr', 19:27:19 INFO - 'ts_paint', 19:27:19 INFO - 'tpaint', 19:27:19 INFO - 'sessionrestore', 19:27:19 INFO - 'sessionrestore_no_auto_restore', 19:27:19 INFO - 'tabpaint']}, 19:27:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['tsvgx', 19:27:19 INFO - 'tsvgr_opacity', 19:27:19 INFO - 'tart', 19:27:19 INFO - 'tscrollx', 19:27:19 INFO - 'cart']}, 19:27:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:27:19 INFO - 'tsvgr_opacity', 19:27:19 INFO - 'tart', 19:27:19 INFO - 'tscrollx', 19:27:19 INFO - 'cart']}, 19:27:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'talos_options': ['--disable-e10s'], 19:27:19 INFO - 'tests': ['tp5o']}, 19:27:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'tests': ['tp5o']}, 19:27:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'talos_options': ['--disable-e10s', 19:27:19 INFO - '--xperf_path', 19:27:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:19 INFO - 'tests': ['tp5n']}, 19:27:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:19 INFO - 'talos_options': ['--xperf_path', 19:27:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:19 INFO - 'tests': ['tp5n']}}, 19:27:19 INFO - 'talos.zip': {'path': '', 19:27:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:27:19 INFO - Running post-action listener: _resource_record_post_action 19:27:19 INFO - ##### 19:27:19 INFO - ##### Finished populate-webroot step (success) 19:27:19 INFO - ##### 19:27:19 INFO - ##### 19:27:19 INFO - ##### Running create-virtualenv step. 19:27:19 INFO - ##### 19:27:19 INFO - Running pre-action listener: _resource_record_pre_action 19:27:19 INFO - Running main action method: create_virtualenv 19:27:19 INFO - Creating virtualenv /builds/slave/test/build/venv 19:27:19 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 19:27:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:27:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:27:19 INFO - Using real prefix '/usr' 19:27:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:27:19 INFO - Installing distribute.............................................................................................................................................................................................done. 19:27:21 INFO - Installing pip.................done. 19:27:21 INFO - Return code: 0 19:27:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:27:21 INFO - Using env: {'DISPLAY': ':0', 19:27:21 INFO - 'HOME': '/home/cltbld', 19:27:21 INFO - 'LANG': 'en_US.UTF-8', 19:27:21 INFO - 'LANGUAGE': 'en_US:en', 19:27:21 INFO - 'LOGNAME': 'cltbld', 19:27:21 INFO - 'MAIL': '/var/mail/cltbld', 19:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:21 INFO - 'MOZ_NO_REMOTE': '1', 19:27:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:21 INFO - 'NO_EM_RESTART': '1', 19:27:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:21 INFO - 'PWD': '/builds/slave/test', 19:27:21 INFO - 'SHELL': '/bin/bash', 19:27:21 INFO - 'SHLVL': '1', 19:27:21 INFO - 'TERM': 'linux', 19:27:21 INFO - 'TMOUT': '86400', 19:27:21 INFO - 'USER': 'cltbld', 19:27:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:21 INFO - '_': '/tools/buildbot/bin/python'} 19:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:27:21 INFO - Cleaning up... 19:27:21 INFO - Return code: 0 19:27:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 19:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:27:21 INFO - Using env: {'DISPLAY': ':0', 19:27:21 INFO - 'HOME': '/home/cltbld', 19:27:21 INFO - 'LANG': 'en_US.UTF-8', 19:27:21 INFO - 'LANGUAGE': 'en_US:en', 19:27:21 INFO - 'LOGNAME': 'cltbld', 19:27:21 INFO - 'MAIL': '/var/mail/cltbld', 19:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:21 INFO - 'MOZ_NO_REMOTE': '1', 19:27:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:21 INFO - 'NO_EM_RESTART': '1', 19:27:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:21 INFO - 'PWD': '/builds/slave/test', 19:27:21 INFO - 'SHELL': '/bin/bash', 19:27:21 INFO - 'SHLVL': '1', 19:27:21 INFO - 'TERM': 'linux', 19:27:21 INFO - 'TMOUT': '86400', 19:27:21 INFO - 'USER': 'cltbld', 19:27:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:21 INFO - '_': '/tools/buildbot/bin/python'} 19:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:22 INFO - Downloading/unpacking psutil>=0.7.1 19:27:22 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 19:27:22 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 19:27:22 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 19:27:22 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 19:27:22 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 19:27:22 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 19:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:27:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:24 INFO - Installing collected packages: psutil 19:27:24 INFO - Running setup.py install for psutil 19:27:24 INFO - building 'psutil._psutil_linux' extension 19:27:24 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 19:27:24 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 19:27:24 INFO - building 'psutil._psutil_posix' extension 19:27:24 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 19:27:24 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 19:27:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:24 INFO - Successfully installed psutil 19:27:24 INFO - Cleaning up... 19:27:24 INFO - Return code: 0 19:27:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:27: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 mozsystemmonitor==0.0.0 19:27:24 INFO - Using env: {'DISPLAY': ':0', 19:27:24 INFO - 'HOME': '/home/cltbld', 19:27:24 INFO - 'LANG': 'en_US.UTF-8', 19:27:24 INFO - 'LANGUAGE': 'en_US:en', 19:27:24 INFO - 'LOGNAME': 'cltbld', 19:27:24 INFO - 'MAIL': '/var/mail/cltbld', 19:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:24 INFO - 'MOZ_NO_REMOTE': '1', 19:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:24 INFO - 'NO_EM_RESTART': '1', 19:27:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:24 INFO - 'PWD': '/builds/slave/test', 19:27:24 INFO - 'SHELL': '/bin/bash', 19:27:24 INFO - 'SHLVL': '1', 19:27:24 INFO - 'TERM': 'linux', 19:27:24 INFO - 'TMOUT': '86400', 19:27:24 INFO - 'USER': 'cltbld', 19:27:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:24 INFO - '_': '/tools/buildbot/bin/python'} 19:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:27:26 INFO - Installing collected packages: mozsystemmonitor 19:27:26 INFO - Running setup.py install for mozsystemmonitor 19:27:26 INFO - Successfully installed mozsystemmonitor 19:27:26 INFO - Cleaning up... 19:27:26 INFO - Return code: 0 19:27:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:27:26 INFO - Using env: {'DISPLAY': ':0', 19:27:26 INFO - 'HOME': '/home/cltbld', 19:27:26 INFO - 'LANG': 'en_US.UTF-8', 19:27:26 INFO - 'LANGUAGE': 'en_US:en', 19:27:26 INFO - 'LOGNAME': 'cltbld', 19:27:26 INFO - 'MAIL': '/var/mail/cltbld', 19:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:26 INFO - 'MOZ_NO_REMOTE': '1', 19:27:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:26 INFO - 'NO_EM_RESTART': '1', 19:27:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:26 INFO - 'PWD': '/builds/slave/test', 19:27:26 INFO - 'SHELL': '/bin/bash', 19:27:26 INFO - 'SHLVL': '1', 19:27:26 INFO - 'TERM': 'linux', 19:27:26 INFO - 'TMOUT': '86400', 19:27:26 INFO - 'USER': 'cltbld', 19:27:26 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:26 INFO - '_': '/tools/buildbot/bin/python'} 19:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:26 INFO - Downloading/unpacking blobuploader==1.2.4 19:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:28 INFO - Downloading blobuploader-1.2.4.tar.gz 19:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:27:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:27:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:29 INFO - Downloading docopt-0.6.1.tar.gz 19:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:27:29 INFO - Installing collected packages: blobuploader, requests, docopt 19:27:29 INFO - Running setup.py install for blobuploader 19:27:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:27:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:27:29 INFO - Running setup.py install for requests 19:27:29 INFO - Running setup.py install for docopt 19:27:29 INFO - Successfully installed blobuploader requests docopt 19:27:29 INFO - Cleaning up... 19:27:30 INFO - Return code: 0 19:27:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:30 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')]} 19:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:30 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')]} 19:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:30 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 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:30 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 19:27:30 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 19:27:30 INFO - Using env: {'DISPLAY': ':0', 19:27:30 INFO - 'HOME': '/home/cltbld', 19:27:30 INFO - 'LANG': 'en_US.UTF-8', 19:27:30 INFO - 'LANGUAGE': 'en_US:en', 19:27:30 INFO - 'LOGNAME': 'cltbld', 19:27:30 INFO - 'MAIL': '/var/mail/cltbld', 19:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:30 INFO - 'MOZ_NO_REMOTE': '1', 19:27:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:30 INFO - 'NO_EM_RESTART': '1', 19:27:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:30 INFO - 'PWD': '/builds/slave/test', 19:27:30 INFO - 'SHELL': '/bin/bash', 19:27:30 INFO - 'SHLVL': '1', 19:27:30 INFO - 'TERM': 'linux', 19:27:30 INFO - 'TMOUT': '86400', 19:27:30 INFO - 'USER': 'cltbld', 19:27:30 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:30 INFO - '_': '/tools/buildbot/bin/python'} 19:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:30 INFO - Running setup.py (path:/tmp/pip-o73uju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:30 INFO - Running setup.py (path:/tmp/pip-Go97g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:30 INFO - Running setup.py (path:/tmp/pip-srFE1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:30 INFO - Running setup.py (path:/tmp/pip-O68kAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:30 INFO - Running setup.py (path:/tmp/pip-Jn1RzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:30 INFO - Running setup.py (path:/tmp/pip-ccJsPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:30 INFO - Running setup.py (path:/tmp/pip-q_TGPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:30 INFO - Running setup.py (path:/tmp/pip-K3WJOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:30 INFO - Running setup.py (path:/tmp/pip-4zrBpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:30 INFO - Running setup.py (path:/tmp/pip-E38R1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:30 INFO - Running setup.py (path:/tmp/pip-CwC6bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:30 INFO - Running setup.py (path:/tmp/pip-QKmF8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:30 INFO - Running setup.py (path:/tmp/pip-sn1olO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:30 INFO - Running setup.py (path:/tmp/pip-Fucu4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:30 INFO - Running setup.py (path:/tmp/pip-fBjmJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:31 INFO - Running setup.py (path:/tmp/pip-AnPkZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:31 INFO - Running setup.py (path:/tmp/pip-XJi2e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:27:31 INFO - Running setup.py install for manifestparser 19:27:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Running setup.py install for mozcrash 19:27:31 INFO - Running setup.py install for mozdebug 19:27:31 INFO - Running setup.py install for mozdevice 19:27:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Running setup.py install for mozfile 19:27:31 INFO - Running setup.py install for mozhttpd 19:27:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Running setup.py install for mozinfo 19:27:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Running setup.py install for mozInstall 19:27:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:27:31 INFO - Running setup.py install for mozleak 19:27:31 INFO - Running setup.py install for mozlog 19:27:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Running setup.py install for moznetwork 19:27:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Running setup.py install for mozprocess 19:27:32 INFO - Running setup.py install for mozprofile 19:27:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Running setup.py install for mozrunner 19:27:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Running setup.py install for mozscreenshot 19:27:32 INFO - Running setup.py install for moztest 19:27:32 INFO - Running setup.py install for mozversion 19:27:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:27:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:27:32 INFO - Cleaning up... 19:27:32 INFO - Return code: 0 19:27:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:27:32 INFO - Using env: {'DISPLAY': ':0', 19:27:32 INFO - 'HOME': '/home/cltbld', 19:27:32 INFO - 'LANG': 'en_US.UTF-8', 19:27:32 INFO - 'LANGUAGE': 'en_US:en', 19:27:32 INFO - 'LOGNAME': 'cltbld', 19:27:32 INFO - 'MAIL': '/var/mail/cltbld', 19:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:32 INFO - 'MOZ_NO_REMOTE': '1', 19:27:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:32 INFO - 'NO_EM_RESTART': '1', 19:27:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:32 INFO - 'PWD': '/builds/slave/test', 19:27:32 INFO - 'SHELL': '/bin/bash', 19:27:32 INFO - 'SHLVL': '1', 19:27:32 INFO - 'TERM': 'linux', 19:27:32 INFO - 'TMOUT': '86400', 19:27:32 INFO - 'USER': 'cltbld', 19:27:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:32 INFO - '_': '/tools/buildbot/bin/python'} 19:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:32 INFO - Running setup.py (path:/tmp/pip-nvIRg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:33 INFO - Running setup.py (path:/tmp/pip-yJmlQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:33 INFO - Running setup.py (path:/tmp/pip-Z0GVQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:33 INFO - Running setup.py (path:/tmp/pip-S4ok6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:33 INFO - Running setup.py (path:/tmp/pip-_Joloo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:33 INFO - Running setup.py (path:/tmp/pip-8eWGm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:33 INFO - Running setup.py (path:/tmp/pip-9vE1R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:33 INFO - Running setup.py (path:/tmp/pip-jTi6U6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:33 INFO - Running setup.py (path:/tmp/pip-Vru7LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:33 INFO - Running setup.py (path:/tmp/pip-rXHONn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:33 INFO - Running setup.py (path:/tmp/pip-h6PvAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:33 INFO - Running setup.py (path:/tmp/pip-2bf9NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:33 INFO - Running setup.py (path:/tmp/pip-BzbU_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:33 INFO - Running setup.py (path:/tmp/pip-1x6SNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:33 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)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:33 INFO - Running setup.py (path:/tmp/pip-jL32Na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:33 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)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:33 INFO - Running setup.py (path:/tmp/pip-Y0yYSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:33 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)) 19:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:33 INFO - Running setup.py (path:/tmp/pip-BopcvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:33 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)) 19:27:33 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)) 19:27:33 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)) 19:27:33 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)) 19:27:33 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)) 19:27:33 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)) 19:27:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:27:33 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 19:27:33 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 19:27:33 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 19:27:33 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 19:27:33 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 19:27:33 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 19:27:35 INFO - Downloading blessings-1.6.tar.gz 19:27:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:27:35 INFO - Installing collected packages: blessings 19:27:35 INFO - Running setup.py install for blessings 19:27:35 INFO - Successfully installed blessings 19:27:35 INFO - Cleaning up... 19:27:36 INFO - Return code: 0 19:27:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:27:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:36 INFO - Reading from file tmpfile_stdout 19:27:36 INFO - Current package versions: 19:27:36 INFO - argparse == 1.2.1 19:27:36 INFO - blessings == 1.6 19:27:36 INFO - blobuploader == 1.2.4 19:27:36 INFO - docopt == 0.6.1 19:27:36 INFO - manifestparser == 1.1 19:27:36 INFO - mozInstall == 1.12 19:27:36 INFO - mozcrash == 0.17 19:27:36 INFO - mozdebug == 0.1 19:27:36 INFO - mozdevice == 0.48 19:27:36 INFO - mozfile == 1.2 19:27:36 INFO - mozhttpd == 0.7 19:27:36 INFO - mozinfo == 0.9 19:27:36 INFO - mozleak == 0.1 19:27:36 INFO - mozlog == 3.2 19:27:36 INFO - moznetwork == 0.27 19:27:36 INFO - mozprocess == 0.22 19:27:36 INFO - mozprofile == 0.28 19:27:36 INFO - mozrunner == 6.11 19:27:36 INFO - mozscreenshot == 0.1 19:27:36 INFO - mozsystemmonitor == 0.0 19:27:36 INFO - moztest == 0.7 19:27:36 INFO - mozversion == 1.4 19:27:36 INFO - psutil == 3.1.1 19:27:36 INFO - requests == 1.2.3 19:27:36 INFO - wsgiref == 0.1.2 19:27:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:27:36 INFO - Using env: {'DISPLAY': ':0', 19:27:36 INFO - 'HOME': '/home/cltbld', 19:27:36 INFO - 'LANG': 'en_US.UTF-8', 19:27:36 INFO - 'LANGUAGE': 'en_US:en', 19:27:36 INFO - 'LOGNAME': 'cltbld', 19:27:36 INFO - 'MAIL': '/var/mail/cltbld', 19:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:36 INFO - 'MOZ_NO_REMOTE': '1', 19:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:36 INFO - 'NO_EM_RESTART': '1', 19:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:36 INFO - 'PWD': '/builds/slave/test', 19:27:36 INFO - 'SHELL': '/bin/bash', 19:27:36 INFO - 'SHLVL': '1', 19:27:36 INFO - 'TERM': 'linux', 19:27:36 INFO - 'TMOUT': '86400', 19:27:36 INFO - 'USER': 'cltbld', 19:27:36 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:36 INFO - '_': '/tools/buildbot/bin/python'} 19:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:27:36 INFO - Cleaning up... 19:27:36 INFO - Return code: 0 19:27:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:27:36 INFO - Using env: {'DISPLAY': ':0', 19:27:36 INFO - 'HOME': '/home/cltbld', 19:27:36 INFO - 'LANG': 'en_US.UTF-8', 19:27:36 INFO - 'LANGUAGE': 'en_US:en', 19:27:36 INFO - 'LOGNAME': 'cltbld', 19:27:36 INFO - 'MAIL': '/var/mail/cltbld', 19:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:36 INFO - 'MOZ_NO_REMOTE': '1', 19:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:36 INFO - 'NO_EM_RESTART': '1', 19:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:36 INFO - 'PWD': '/builds/slave/test', 19:27:36 INFO - 'SHELL': '/bin/bash', 19:27:36 INFO - 'SHLVL': '1', 19:27:36 INFO - 'TERM': 'linux', 19:27:36 INFO - 'TMOUT': '86400', 19:27:36 INFO - 'USER': 'cltbld', 19:27:36 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:36 INFO - '_': '/tools/buildbot/bin/python'} 19:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:36 INFO - Downloading/unpacking jsonschema 19:27:36 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 19:27:36 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 19:27:36 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 19:27:36 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 19:27:36 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 19:27:36 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 19:27:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:27:38 INFO - Downloading/unpacking functools32 (from jsonschema) 19:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:38 INFO - Downloading functools32-3.2.3-2.tar.gz 19:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:27:38 INFO - warning: no files found matching '*.txt' 19:27:38 INFO - no previously-included directories found matching 'build' 19:27:38 INFO - no previously-included directories found matching 'dist' 19:27:38 INFO - no previously-included directories found matching '.git*' 19:27:38 INFO - Installing collected packages: jsonschema, functools32 19:27:38 INFO - Running setup.py install for functools32 19:27:38 INFO - warning: no files found matching '*.txt' 19:27:38 INFO - no previously-included directories found matching 'build' 19:27:38 INFO - no previously-included directories found matching 'dist' 19:27:38 INFO - no previously-included directories found matching '.git*' 19:27:38 INFO - Successfully installed jsonschema functools32 19:27:38 INFO - Cleaning up... 19:27:38 INFO - Return code: 0 19:27:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:38 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 0x7f45090aff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, '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-1463105711.53244-2086596609', '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 19:27:38 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 19:27:38 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 19:27:38 INFO - Using env: {'DISPLAY': ':0', 19:27:38 INFO - 'HOME': '/home/cltbld', 19:27:38 INFO - 'LANG': 'en_US.UTF-8', 19:27:38 INFO - 'LANGUAGE': 'en_US:en', 19:27:38 INFO - 'LOGNAME': 'cltbld', 19:27:38 INFO - 'MAIL': '/var/mail/cltbld', 19:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:38 INFO - 'MOZ_NO_REMOTE': '1', 19:27:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:38 INFO - 'NO_EM_RESTART': '1', 19:27:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:38 INFO - 'PWD': '/builds/slave/test', 19:27:38 INFO - 'SHELL': '/bin/bash', 19:27:38 INFO - 'SHLVL': '1', 19:27:38 INFO - 'TERM': 'linux', 19:27:38 INFO - 'TMOUT': '86400', 19:27:38 INFO - 'USER': 'cltbld', 19:27:38 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:38 INFO - '_': '/tools/buildbot/bin/python'} 19:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:39 INFO - Downloading/unpacking flake8 19:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:27:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:27:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:27:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:41 INFO - no previously-included directories found matching 'docs/_build' 19:27:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:27:41 INFO - Running setup.py install for pep8 19:27:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:41 INFO - no previously-included directories found matching 'docs/_build' 19:27:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:27:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:27:41 INFO - Cleaning up... 19:27:41 INFO - Return code: 0 19:27:41 INFO - Running post-action listener: _resource_record_post_action 19:27:41 INFO - Running post-action listener: _start_resource_monitoring 19:27:41 INFO - Starting resource monitoring. 19:27:41 INFO - ##### 19:27:41 INFO - ##### Finished create-virtualenv step (success) 19:27:41 INFO - ##### 19:27:41 INFO - ##### 19:27:41 INFO - ##### Running install step. 19:27:41 INFO - ##### 19:27:41 INFO - Running pre-action listener: _resource_record_pre_action 19:27:41 INFO - Running main action method: install 19:27:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:42 INFO - Reading from file tmpfile_stdout 19:27:42 INFO - Detecting whether we're running mozinstall >=1.0... 19:27:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:27:42 INFO - Reading from file tmpfile_stdout 19:27:42 INFO - Output received: 19:27:42 INFO - Usage: mozinstall [options] installer 19:27:42 INFO - Options: 19:27:42 INFO - -h, --help show this help message and exit 19:27:42 INFO - -d DEST, --destination=DEST 19:27:42 INFO - Directory to install application into. [default: 19:27:42 INFO - "/builds/slave/test"] 19:27:42 INFO - --app=APP Application being installed. [default: firefox] 19:27:42 INFO - mkdir: /builds/slave/test/build/application 19:27:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:27:58 INFO - Reading from file tmpfile_stdout 19:27:58 INFO - Output received: 19:27:58 INFO - /builds/slave/test/build/application/firefox/firefox 19:27:58 INFO - Running post-action listener: _resource_record_post_action 19:27:58 INFO - ##### 19:27:58 INFO - ##### Finished install step (success) 19:27:58 INFO - ##### 19:27:58 INFO - ##### 19:27:58 INFO - ##### Running run-tests step. 19:27:58 INFO - ##### 19:27:58 INFO - Running pre-action listener: _resource_record_pre_action 19:27:58 INFO - Running main action method: run_tests 19:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:58 INFO - Python 2.7.3 19:27:58 INFO - Return code: 0 19:27:58 INFO - grabbing minidump binary from tooltool 19:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15449c0>, '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 0x15458c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1545d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:27:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:27:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:27:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:58 INFO - Return code: 0 19:27:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:27:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:27:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:27:59 INFO - Return code: 0 19:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:59 INFO - Using env: {'DISPLAY': ':0', 19:27:59 INFO - 'HOME': '/home/cltbld', 19:27:59 INFO - 'LANG': 'en_US.UTF-8', 19:27:59 INFO - 'LANGUAGE': 'en_US:en', 19:27:59 INFO - 'LOGNAME': 'cltbld', 19:27:59 INFO - 'MAIL': '/var/mail/cltbld', 19:27:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:59 INFO - 'MOZ_NO_REMOTE': '1', 19:27:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:59 INFO - 'NO_EM_RESTART': '1', 19:27:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:59 INFO - 'PWD': '/builds/slave/test', 19:27:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:59 INFO - 'SHELL': '/bin/bash', 19:27:59 INFO - 'SHLVL': '1', 19:27:59 INFO - 'TERM': 'linux', 19:27:59 INFO - 'TMOUT': '86400', 19:27:59 INFO - 'USER': 'cltbld', 19:27:59 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1463105711.53244-2086596609', 19:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:59 INFO - '_': '/tools/buildbot/bin/python'} 19:27:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/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 19:27:59 INFO - mozversion application_buildid: 20160512163011 19:27:59 INFO - mozversion application_changeset: c71165b9f120b33ce68e5eef97eb8480daedeb65 19:27:59 INFO - mozversion application_display_name: Nightly 19:27:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:59 INFO - mozversion application_name: Firefox 19:27:59 INFO - mozversion application_remotingname: firefox 19:27:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:27:59 INFO - mozversion application_vendor: Mozilla 19:27:59 INFO - mozversion application_version: 49.0a1 19:27:59 INFO - mozversion platform_buildid: 20160512163011 19:27:59 INFO - mozversion platform_changeset: c71165b9f120b33ce68e5eef97eb8480daedeb65 19:27:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:27:59 INFO - mozversion platform_version: 49.0a1 19:27:59 INFO - using testdate: 1463106479 19:27:59 INFO - actual date: 1463106479 19:27:59 INFO - starting webserver on 'localhost:47873' 19:27:59 INFO - SUITE-START | Running 2 tests 19:27:59 INFO - TEST-START | dromaeo_css 19:27:59 INFO - operating with platform_type : linux_ 19:27:59 INFO - Initialising browser for dromaeo_css test... 19:27:59 INFO - TEST-INFO | started process 16806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUqRxZy/profile http://localhost:47873/getInfo.html) 19:28:04 INFO - PROCESS | 16806 | __metrics Screen width/height:1600/1200 19:28:04 INFO - PROCESS | 16806 | colorDepth:24 19:28:04 INFO - PROCESS | 16806 | Browser inner width/height: 1024/697 19:28:04 INFO - PROCESS | 16806 | __metrics 19:28:05 ERROR - PROCESS | 16806 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:28:05 INFO - TEST-INFO | 16806: exit 0 19:28:05 INFO - Browser initialized. 19:28:05 INFO - Running cycle 1/1 for dromaeo_css test... 19:28:05 INFO - TEST-INFO | started process 16885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUqRxZy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:17 INFO - PROCESS | 16885 | RSS: Main: 150913024 19:28:17 INFO - PROCESS | 16885 | 19:29:08 INFO - PROCESS | 16885 | 1463106548311 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] 19:31:16 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47873/tests/dromaeo/cssquery-ext.html) 19:31:17 INFO - PROCESS | 16885 | RSS: Main: 258506752 19:31:17 INFO - PROCESS | 16885 | 19:34:19 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-ext.html (next: http://localhost:47873/tests/dromaeo/cssquery-jquery.html) 19:34:19 INFO - PROCESS | 16885 | RSS: Main: 230965248 19:34:19 INFO - PROCESS | 16885 | 19:37:21 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47873/tests/dromaeo/cssquery-mootools.html) 19:37:22 INFO - PROCESS | 16885 | RSS: Main: 240037888 19:37:22 INFO - PROCESS | 16885 | 19:40:24 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47873/tests/dromaeo/cssquery-prototype.html) 19:40:24 INFO - PROCESS | 16885 | RSS: Main: 232325120 19:40:24 INFO - PROCESS | 16885 | 19:43:27 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47873/tests/dromaeo/cssquery-yui.html) 19:43:28 INFO - PROCESS | 16885 | RSS: Main: 217563136 19:43:28 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | Cycle 1(1): loaded http://localhost:47873/tests/dromaeo/cssquery-yui.html (next: http://localhost:47873/tests/dromaeo/cssquery-dojo.html) 19:46:30 INFO - PROCESS | 16885 | RSS: Main: 277356544 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | __start_tp_report 19:46:30 INFO - PROCESS | 16885 | _x_x_mozilla_page_load 19:46:30 INFO - PROCESS | 16885 | _x_x_mozilla_page_load_details 19:46:30 INFO - PROCESS | 16885 | |i|pagename|runs| 19:46:30 INFO - PROCESS | 16885 | |0;dojo.html;3102.0228627982187;3111.3155105876713;3142.9528557071635;3146.496560550311;3187.9840600796992;5182.17085266357;5190.195519694444;5301.7879284828605;5334.759935802888;5355.116405793045;5311.707856067915;5314.5216930476345;5324.866878328044;5364.195370694388;5461.290032295806;4798.712077275357;4832.73419961902;4894.388201474816;4929.65492415531;4988;4860.05228980349;4896.632752543555;4913.631477639174;4921.2618107284015;4931.358923339961;4669.019505903764;4675.2052151134385;4698.154332220199;4720.693154944935;4765.666403351766;4736.407949006374;4829.758512074382;4840;4852.781624826856;4875.853724388274;4649.442066951953;4758.02460495601;4789.568938795525;4796.664233503656;4834.178189707758;5219.478052194788;5291.312129423189;5295.867603309948;5308.256844041837;5326.360836699582;5662.688552129631;5692;5696.458836410535;5703.287089113861;5714.028615135437;5510.586705997026;5569.470900264469;5579.414161513064;5735.283089613798;5771.740271687784;5300.708461034642;5317.654352467077;5345.786168553292;5349.358077030743;5356.812511562116;5775.768969241268;5801.216835727208;5807.593468457168;5810.535157187415;5823.010088285002;5372.892542149135;5499.340079190442;5549.223108764776;5560.610757246954;5615.157726341;5692.345380281238;5696.487316141484;5704.828855134353;5709.571782116358;5711.942880571224;5643.125315576092;5678.77284908599;5682.119271512839;5684.37471878093;5688.601797874192;5442.455754424526;5550.91673624896;5623.2408624835125;5634.23937768396;5674.943250567441;4277.593628605277;4311.1808756336195;4313.374560688745;4314.611684948307;4349.673774466864;3785.413260944558;3860.575336712959;3872.9736619795776;3888.475055867422;3912.374020156761;5299.788008479618;5358.356997160287;5371.811986580451;5403.405625381205;5403.459654034644;5704.942950570441;5705.058665320175;5710.057840456361;5719.142128680731;5730.885382292414;5190.870228244324;5285.762140703678;5365.007473617393;5405.405405405402;5458.4814442627885;4865.95134048655;4920.8769780755765;4948.703077815343;4949.925751113737;4955.801767929314;5954.642721436728;5970.402959703995;5970.432808883149;5970.731317090741;5976.5218782497295;5419.430959749169;5597.720113994203;5614.943850561442;5752.942470575241;5784.2769653793275;5866.823995280149;5882.382349853375;5887.852803679943;5890.410958904075;5897.823065308047;5572.832815015556;5573.804916828054;5575.721213939368;5575.972120139373;5577.107662773937;5505.779768809363;5651.463111004448;5684.8010319638615;5706.828795136153;5724;5629.902169077154;5632.183333416713;5633.605647604629;5644.8024319148635;5660;5522.779088836402;5559.722013899208;5566.081596536526;5567.7494512747835;5578.832635020955;5258.106121959199;5436.048691478896;5484.040292948798;5527.198556209408;5563.13771365439;5662.660240385425;5672.744726487235;5698.515626171822;5729.9713501432225;5736.42635736423;5288.947110528846;5527;5527.5577953764405;5534.806281780279;5594.636348637326 19:46:30 INFO - PROCESS | 16885 | |1;ext.html;573.7647564498557;593.1458699472759;603.131490653459;604.2175382879171;606.31486420345;32329.353412931756;36303.81848090759;36309.45535816962;36313.27373452536;43974.340384894174;42808.3578746319;47530.287045694255;47656.2851557226;47866.76066619671;47874;8779.78050548735;8952.552372381388;8964.910350896473;8966.86549701755;8974.237189838872;8804.295656347509;8922.687705930293;8946.955265223665;8947.865782013243;8973.371863969525;8949.95525022374;8965;8994.460332380078;9005.459672419644;9023.548822558878;8957.95521022394;8966.282697384193;9005.234555062827;9008.144226298491;9016.323775716848;8730.432521886058;8831.470111793249;8835.69075082369;8843.734687959372;8866.911330886673;42767.78616106931;43010.78494607538;43024.13951720979;43028;43131.56868431307;3593.2386046140473;3868.845246190149;4258.26226278028;4324.048709283952;4374.868753937387;32909.17727056819;33061.83469082663;33098.3380332392;33456.832715836266;34628.653713462794;4062.2173810939603;4102.774347410891;4172.123853990665;4196.769177695225;4262.46719160105;349461;351355;353039;356085.2195739031;357533;32279.838600806845;32950.176245594055;61865.690671546356;62310.68844655748;62848.37151628425;38124.23751524954;38149.427758583646;38245.23509529849;38286.61713382886;38565.42151867724;14294;14344.641383965485;14363.928180359031;14373;14433.20617366055;11520;11585.189036767513;11602.07183425324;11611.129165312635;11655.825162622568;8621.965364156216;8641.697540586048;8657.913420865836;8673.869891951626;8744.169303916118;3370.5281260623533;3382.915427114342;3384.983075084658;3387.3902697514686;3394.0835974287297;1041.1571518412368;1129.0628778114146;1141.748815260658;1145.7479354541988;1148.2019996102795;33121.50317745236;33373.83313083419;33430.8328458361;33710.157246069044;34361.14097147541;1078.3368228539548;1107.789519991199;1162.5872815150499;1188.369531082551;1214.3909320150835;17397.47807565775;17778.37775677845;17805.554861128574;17970.91014544919;17976.730349044774;529937;530011;536804;537258;537398;61481;62141.378586214465;62203.688981554806;62206;62316.688416558536;1290.0324756432676;1360.6870419803502;1410.2102822419479;1419.6734751007325;1430.7496188167238;32723;34136.658633413346;34290.14274643154;34577.25743480098;34814.47778283277;50168.99662006812;50584;50585.747071264406;50708;50864.49135508598;349856;355751.3186511074;356211;357077;358472;25753.742462575134;28174.85912570424;28219.57670634983;29085.709142909145;29409;46680.29979550242;47259;48733;50699;51206;39777.60222397739;42095.36856947087;43593.346099807895;44502;45353.77323113364;42342.36486452767;43107.568924310355;43115;43330;43704.78147609369;41220.587794121675;42430.151396972506;42601;43297.7835110835;43371.783141084095;35653.46519802257;36692.44961325529;37025.44461833128;37485.625143749305;38062.80968595139;28626.14121576356;28824.279393015342;28848;28974.565381519715;29211.7078829209 19:46:30 INFO - PROCESS | 16885 | |2;jquery.html;400.83165070670316;410.3291119020401;412.62863422919366;419.8362638570957;430.27928220231104;13513.459461621524;17540.649187016268;19332.806671933293;19385;19625.607487850233;14073.788893166586;17129.314827406888;17922.910385448053;18197.90901045493;19035.619287614238;5701.372848986608;5816.331121920991;5859.589828711992;5862.325832529271;5904.173415721781;5722.971385143069;5779.104238842965;5780.1907732917425;5809.419058094178;5846.941530584682;4107.897302567445;6137.938620613803;6485.772997945073;6487.3836985486305;6604.801855944305;6434.453071488929;6531.020346947948;6541.313162118005;6552.180977377828;6570.8028759137305;5582.804601838957;5721.4564616361795;5738.971305143489;5743.081107022898;5756.7121643917635;21715.782842171535;21986.89006554973;22048;22352.21767238155;22567.435814104614;2409.506051259488;2427.2354208424467;2509.736477669855;2875.6692980307325;3161.1780936956325;13496.797548036788;13959.441622335095;14161;14224.35990380425;14610.561683149523;1927.9903600482048;1935.94192174235;1960.8529360298035;1992.432156835295;2007.437917383135;83294.16705832924;83648.58175709144;83768.16231837665;83969;85307;10229;10354;10667.19996000303;10754.300970436896;10862.131029517619;14549.709005819825;14665.92667036658;14681.19253441071;14721.411143554138;14774.48289309869;7567.886481702779;7608.429367797326;7640.617969101522;7713.807154821062;7729.5748733819055;7307.926920730831;7372.59450730215;7403.518771279957;7427.740029099064;7458.843879198733;5760.481556659921;6112.052631842072;6553.836154096186;6590.769323073765;6664.766733164316;1952.4045166224437;1954.101113487783;1963.2539634938632;1965.4398496428448;1968.0750047477663;1000.554753134848;1029.7425643589104;1149.36210403227;1160.9071274298205;1180.1561883253344;16636.916815415847;18664.34674786403;18958.81041189598;18993.145308460877;19123.90438047829;996.4220751963844;1020.2195320579763;1025.933314334566;1047.4553232319151;1071.49104175516;10643.467826608794;10934.026488426785;10984.725381865357;11007.889921100847;11034;406733;415565;429798;436324;437704;78440;81371.9780794096;81881;81988.18011819925;82858.17141828504;1085.3325204998869;1118.927269727465;1134.5235001299475;1138.0497284767312;1148.626696323698;16531.01627804671;16862;16975.490735277963;16984.23570939286;17031.914840425718;35477.93566193018;37952.430713538764;38131.237375252895;38907.221855562166;40228.79885600553;49492;50322.74838625784;50426.49573504218;50794.492055078976;51150.48849511457;8434.662613495391;8547.786305342168;8611.13888611134;8780.692675756318;8959.686410975584;10675.466226688479;12205.52142639379;12798;13261.138026028277;13383.732325353243;11404.600838970597;11673.416329183337;11883.168178227786;12051.879481205426;12379.257244565355;6517.674116294261;11464;11676.416179190837;12165.148439609142;12282.324472154118;11768.058555315383;11971.10216733731;12017.519299228283;12068.155229133876;12361.07291953089;8854.026057133582;8928.910710893068;8992.685256016008;9054.683086091954;9172.174504294646;9439.244860411289;11837.112216583619;12131;12186.329751863732;12591.685207869876 19:46:30 INFO - PROCESS | 16885 | |3;mootools.html;1397.5248415538715;1557.4704600435853;1587.5713557339518;1778.9555261118471;1907.4277716684999;8551.828963420729;8934.240589549896;8937.419067760598;9243.6302547898;9259.583318750656;8985.146411090936;9213.493257870814;9216.354855160142;9242.861357079631;9252.768680782967;2107.1782005018053;2336.836421450499;2383.547126046046;2408.506256217471;2446.1683027770555;2438.430620488178;2459;2462.9876850615724;2468.7407822178684;2469.320936742401;2425.454272788626;2438.792702620279;2447.608382658775;2448.167623008177;2451.472933319336;2062.8143467087957;2353.376355265856;2402.423418379594;2432.6351047342864;2447.779699827024;2356.175338631482;2362.14962613459;2368.2184878990065;2369.3839601703507;2402.1592442644933;9092.681756138503;9204.355695101345;9222.953885230598;9226.123518265755;9266;5469.699166545838;5637.8590535236535;5639.323281206241;5647.407022262644;5660.122680984454;3505.91235219119;3508.4211105167615;3598.586162591283;3666.9083272918124;3674.8530058797614;1110.9277896936674;1119.7704470583592;1130.299214487015;1152.7924973504767;1214.1258294028285;12932.224066555942;12935.547255846095;12953.87046129536;12960;12962.546310879168;4932.235503496964;4932.778024988884;5030.974845125751;5039.82360617384;5051.292819005343;10723.785524289626;10735.087517561095;10768.192385571114;10772.192085593611;10783;4876.4392094909535;5553;5633.352164501053;5656.519195868315;5668.744906479218;4985.252212168131;5145.511176438233;5171.3277273954145;5202.167653175474;5207.869803254949;4134.689898257643;4154.376843473503;4195.30777421722;4218.093109981369;4238.639715624145;1252.467701226975;1267.163671976491;1285.7235694325766;1293.043148070421;1294.249335385474;928.5728564860104;967.2068903499204;1039.8336266197373;1044.7858189071221;1065.7495488560182;6324.08300796382;6417.326180750954;6443.742250310029;6471.546991710629;6530.477561795045;458.27363628648965;470.60469205867236;470.76226505614443;472.87468820762524;480.79566184371504;4377.9124417511475;4424.623906967944;4511.5262897395305;4515.029268707243;4533.025399539115;11123;11614.477348519336;11730.12024098196;11744.647660570197;11753.118516111326;11409;11444.198906076495;11458.885411145782;11558.826617600742;11573.594924177782;8208.015038195455;8642.611082501302;8664.393492455467;8784.912150878537;8853.51305678181;6231.844203894939;6439.291677915332;6475.805725828232;6523.217213934358;6547.214334279917;10229.84655230187;10342.741431464274;10343.431111288952;10405.06354428105;10425.9478702606;10325.896741032493;10599.47002649849;10649.680509584725;10726;10761.730956726144;7328.78013659591;7555.320021198118;7558.735444259424;8179.67281308741;8327.333813295043;8236.464629799044;8314.958425208077;8517.276031537142;8561.48631082137;8784;7631.809204769926;7805.18045605226;7807.960960195162;7891.408144389079;7950.522968621901;7554.886676699744;7783.104942931409;7839.686412543434;7916.010498687664;7942.44402891792;7066.964665176847;7074.823129421806;7078.68145933424;7087.362137407658;7118.359347658736;5289.100852855101;5401.567874570103;5531.668099914018;5559.888802223852;5624.437556244343;5418.566514678895;5420.159875219348;5459.836204913859;5508.063629183129;5562.582806289463 19:46:30 INFO - PROCESS | 16885 | |4;prototype.html;331.4067818604698;333.8063922924704;337.81251405469953;339.7757480063158;340.15980528095594;5055.772490237939;5159.922601160981;5273.604479664022;5308.23030660554;5317.9468205317935;5153.510416510426;5175.637705360627;5201.375834899808;5224.947750522494;5227.032998895207;3886.4947556817574;3929.6266854648907;4064.97967510162;4069;4082.530508991474;4044.858429954952;4055.1686904184717;4061.918761624766;4069.003094241915;4096.180763847227;3826.8086595670243;4044.292248856453;4066.4103704962763;4086.9182616347657;4093.564329224107;3917;4044.4135600337786;4047.028713108862;4061.1471590965934;4063.7155399122075;3557.181848174934;3565.358235517606;3576.9284614307567;3581.3195492856275;3582.677559019675;4911.796609830614;4946.752662366904;4954.752262386866;4981.978694367682;5029.309319514043;4475.239209334389;4568.291914753218;4575.9084818303445;4590.793414296331;4609.884752881171;5278.9208161877605;5303.496167864047;5305.44292849253;5321.600879933982;5330.333708286465;3847.5960024197334;3865.613438656112;3874.2832575973534;3881.146147847469;3891.8637847675477;5377.354717433894;5476.17857321405;5536.612437129363;5571.164325351229;5612.270404847384;4012.137390461065;4035.29382358092;4051.594840515984;4056.249593825152;4071.694622903294;4090.897727556835;4095.24238015968;4098.897527561835;4108.116754897652;4109.97945010273;4404.801783919731;4613;4617.053504031665;4640.9535904640525;4646.976765116153;5405.21624364465;5418.485243901823;5425.26758887553;5435.1303791393975;5442.782288708487;1357.8099066130749;1376.6971266321393;1382.6197795606288;1396.6508372906774;1399.6081097292774;880.6213328268777;887.9112088791198;936.4147407870081;947.4788866123605;963.1139351796364;5187.377514698261;5189.662671926389;5207.3336532309295;5207.817726379559;5224.686518808883;5460.570046329425;5501.229827824108;5521.861953451196;5552.805651802168;5552.888942221133;5066.518680725325;5156.839711065056;5200.479952004845;5202.3497062867145;5207.453217412193;2875.9712402875703;2911.344947386821;2915.212892519008;2929.0041385928885;2938.7942844000713;5432.91850622241;5639.464250896158;5650.97174514133;5654.46282603152;5681.57388195887;5550.222968784374;5554.08357620988;5558.6942718150085;5563.693996830132;5568.972155139199;5626.212330273765;5626.943730562723;5653.660780353192;5695.180885461723;5704.629199102045;5314.096603577324;5438.700871452108;5443.339781366722;5457.890842183214;5460.6996615186545;5421.91256366294;5439.918401224064;5451.700156491431;5466.3713672928125;5467.261919640798;5669.5180909621495;5675.517581005661;5679.375268720355;5688.288963879515;5756.769729210785;3846.3653497173773;3906.5312162540677;3992.0818211810865;4016.4376987222395;4017.2367250222364;5441.918371224514;5473.480019398151;5480.972595136999;5497.038018346693;5524.06090964545;5314.388845282688;5338.332708411449;5340.225667278145;5369.087255166709;5375.489328513785;5141.53726164647;5291.576673866004;5297.364316282071;5307.52232299095;5361.35663720356;5072.391313042311;5133.5882632276425;5192.870178245574;5216.6697492139265;5226.085435048927;5361.418381577522;5364.704941228114;5372.919406208832;5383.40782513931;5417.729113544228;5283.603729720363;5301.496357845998;5307.150855863043;5324.760385782572;5363.973180134074 19:46:30 INFO - PROCESS | 16885 | |5;yui.html;749.3031480722927;849.5285116760198;862.3963225741982;863.1996792698442;869.3610196505571;14212.360443780042;15215;15232.39070437184;15348.539543813675;15496.225188740545;14261.930355223349;14324.212168330738;14354;14355.210463424508;14436.350364233609;668.8896332105209;787.1970589998199;817.9631916563769;819.0785366462729;821.7041864928651;825.3809642767924;826.9669213231464;827.7971896885272;827.9710210142646;834.0866750907777;725.0646665801111;875.5928493250638;875.8029443375221;876.7545087375513;879.9032106468284;865.5974971638187;877.7981064355203;879.2702057292496;883.1786438612088;885.7564169853277;640.0303540136697;640.903864420336;642.0754114075716;645.6771614192903;647.2459584583954;14528.273586320642;14542.200178990151;14571.324297705794;14593.197374144418;14598.489052883098;13898.93050534751;13901.513447029305;13996.720065598733;14123.576292711236;14145.151290922578;11920.105992050545;12004.639860804102;12134.696632584168;12257.448414821265;12429.813552796715;4453;4486.618637415823;4511.751853648048;4567.703099298535;4650.18621741194;57121.14318285229;57790.13314800282;58004;58237.70881145567;59322;11295.887041129648;11346.716332091599;11462.369569673581;11481.655550333504;11658.358790266617;20619.175232990514;20638.484037899172;20640.380788576367;20650.58698826015;20666;8376.874346884802;8550.401471897032;8675.045744968049;8691.739247822576;8829.426087304304;4794.400699912511;4836.347093142451;4862.343583616166;4895.755212239445;4931.679440836334;3486.38988177068;3560.750747447654;3583.3191693578137;3590.856365745341;3596.17288023756;502.37705245495323;655.8360409897525;684.2644157530674;687.9105716256854;707.2856415020763;717.1680850213727;766.8734658781239;780.5706861226303;786.9016372953381;788.0897563314381;9305.860412093823;9700.369475984038;9744.90255097454;9745.610175592898;9767.755806104904;727.2328242370984;741.6810771368364;760.5396542186023;775.0853992289153;779.8830175473724;4542.250528662799;4557.749323787223;4617.953820461819;4810.350602668665;4828.758562071883;69789.30210697828;70134.2986570138;71995.28004719886;73371.26628733751;73922;63265.68367158135;63677.363226367146;63981.680091600174;64667;65929;55729;56711.43288567144;57693.71153144291;57817.710911445174;58826;9206.953965230132;9285.86071208946;9439.669611563562;9506.33290116674;9587.664431744855;37479.25041499209;38061.80969095137;38344.80827595844;38758;38823.611763882;47444;51961.48038519567;52000.21999669932;52241.47758522366;52399;11151.888481115086;11327.943360283145;12356.938215308866;12365.258084514959;12522.747725227404;12550.064111767515;12651.746965060463;13085.280309583064;13165.275909824668;13288.534901278408;12134.817977730518;12165.270083795001;12318.384080796104;12434.191777534432;12640.810387844374;11928.224665396721;12036.338001410006;12169.148159628741;12405.44175512086;12800;11695.70862205333;11838.28970261787;11999.220050696676;12114.878851211375;12549;9439.150476457151;9506.524673766145;9646.614135434786;9688.515574221401;9994.700158995242;9340.392874463138;9461.810763784548;9687.9031209687;9699.66051188205;9741.172000379764 19:46:30 INFO - PROCESS | 16885 | __end_tp_report 19:46:30 INFO - PROCESS | 16885 | __start_cc_report 19:46:30 INFO - PROCESS | 16885 | _x_x_mozilla_cycle_collect,830 19:46:30 INFO - PROCESS | 16885 | __end_cc_report 19:46:30 INFO - PROCESS | 16885 | __startTimestamp1463107590781__endTimestamp 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | ------- Summary: start ------- 19:46:30 INFO - PROCESS | 16885 | Number of tests: 6 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#0] dojo.html Cycles:165 Average:5278.38 Median:5499.34 stddev:599.07 (10.9%) stddev-sans-first:576.06 19:46:30 INFO - PROCESS | 16885 | Values: 3102.0 3111.3 3143.0 3146.5 3188.0 5182.2 5190.2 5301.8 5334.8 5355.1 5311.7 5314.5 5324.9 5364.2 5461.3 4798.7 4832.7 4894.4 4929.7 4988.0 4860.1 4896.6 4913.6 4921.3 4931.4 4669.0 4675.2 4698.2 4720.7 4765.7 4736.4 4829.8 4840.0 4852.8 4875.9 4649.4 4758.0 4789.6 4796.7 4834.2 5219.5 5291.3 5295.9 5308.3 5326.4 5662.7 5692.0 5696.5 5703.3 5714.0 5510.6 5569.5 5579.4 5735.3 5771.7 5300.7 5317.7 5345.8 5349.4 5356.8 5775.8 5801.2 5807.6 5810.5 5823.0 5372.9 5499.3 5549.2 5560.6 5615.2 5692.3 5696.5 5704.8 5709.6 5711.9 5643.1 5678.8 5682.1 5684.4 5688.6 5442.5 5550.9 5623.2 5634.2 5674.9 4277.6 4311.2 4313.4 4314.6 4349.7 3785.4 3860.6 3873.0 3888.5 3912.4 5299.8 5358.4 5371.8 5403.4 5403.5 5704.9 5705.1 5710.1 5719.1 5730.9 5190.9 5285.8 5365.0 5405.4 5458.5 4866.0 4920.9 4948.7 4949.9 4955.8 5954.6 5970.4 5970.4 5970.7 5976.5 5419.4 5597.7 5614.9 5752.9 5784.3 5866.8 5882.4 5887.9 5890.4 5897.8 5572.8 5573.8 5575.7 5576.0 5577.1 5505.8 5651.5 5684.8 5706.8 5724.0 5629.9 5632.2 5633.6 5644.8 5660.0 5522.8 5559.7 5566.1 5567.7 5578.8 5258.1 5436.0 5484.0 5527.2 5563.1 5662.7 5672.7 5698.5 5730.0 5736.4 5288.9 5527.0 5527.6 5534.8 5594.6 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#1] ext.html Cycles:180 Average:57143.86 Median:22661.68 stddev:112218.15 (495.2%) stddev-sans-first:112452.58 19:46:30 INFO - PROCESS | 16885 | Values: 573.8 593.1 603.1 604.2 606.3 32329.4 36303.8 36309.5 36313.3 43974.3 42808.4 47530.3 47656.3 47866.8 47874.0 8779.8 8952.6 8964.9 8966.9 8974.2 8804.3 8922.7 8947.0 8947.9 8973.4 8950.0 8965.0 8994.5 9005.5 9023.5 8958.0 8966.3 9005.2 9008.1 9016.3 8730.4 8831.5 8835.7 8843.7 8866.9 42767.8 43010.8 43024.1 43028.0 43131.6 3593.2 3868.8 4258.3 4324.0 4374.9 32909.2 33061.8 33098.3 33456.8 34628.7 4062.2 4102.8 4172.1 4196.8 4262.5 349461.0 351355.0 353039.0 356085.2 357533.0 32279.8 32950.2 61865.7 62310.7 62848.4 38124.2 38149.4 38245.2 38286.6 38565.4 14294.0 14344.6 14363.9 14373.0 14433.2 11520.0 11585.2 11602.1 11611.1 11655.8 8622.0 8641.7 8657.9 8673.9 8744.2 3370.5 3382.9 3385.0 3387.4 3394.1 1041.2 1129.1 1141.7 1145.7 1148.2 33121.5 33373.8 33430.8 33710.2 34361.1 1078.3 1107.8 1162.6 1188.4 1214.4 17397.5 17778.4 17805.6 17970.9 17976.7 529937.0 530011.0 536804.0 537258.0 537398.0 61481.0 62141.4 62203.7 62206.0 62316.7 1290.0 1360.7 1410.2 1419.7 1430.7 32723.0 34136.7 34290.1 34577.3 34814.5 50169.0 50584.0 50585.7 50708.0 50864.5 349856.0 355751.3 356211.0 357077.0 358472.0 25753.7 28174.9 28219.6 29085.7 29409.0 46680.3 47259.0 48733.0 50699.0 51206.0 39777.6 42095.4 43593.3 44502.0 45353.8 42342.4 43107.6 43115.0 43330.0 43704.8 41220.6 42430.2 42601.0 43297.8 43371.8 35653.5 36692.4 37025.4 37485.6 38062.8 28626.1 28824.3 28848.0 28974.6 29211.7 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#2] jquery.html Cycles:180 Average:26666.41 Median:22459.83 stddev:70241.26 (312.7%) stddev-sans-first:70410.62 19:46:30 INFO - PROCESS | 16885 | Values: 400.8 410.3 412.6 419.8 430.3 13513.5 17540.6 19332.8 19385.0 19625.6 14073.8 17129.3 17922.9 18197.9 19035.6 5701.4 5816.3 5859.6 5862.3 5904.2 5723.0 5779.1 5780.2 5809.4 5846.9 4107.9 6137.9 6485.8 6487.4 6604.8 6434.5 6531.0 6541.3 6552.2 6570.8 5582.8 5721.5 5739.0 5743.1 5756.7 21715.8 21986.9 22048.0 22352.2 22567.4 2409.5 2427.2 2509.7 2875.7 3161.2 13496.8 13959.4 14161.0 14224.4 14610.6 1928.0 1935.9 1960.9 1992.4 2007.4 83294.2 83648.6 83768.2 83969.0 85307.0 10229.0 10354.0 10667.2 10754.3 10862.1 14549.7 14665.9 14681.2 14721.4 14774.5 7567.9 7608.4 7640.6 7713.8 7729.6 7307.9 7372.6 7403.5 7427.7 7458.8 5760.5 6112.1 6553.8 6590.8 6664.8 1952.4 1954.1 1963.3 1965.4 1968.1 1000.6 1029.7 1149.4 1160.9 1180.2 16636.9 18664.3 18958.8 18993.1 19123.9 996.4 1020.2 1025.9 1047.5 1071.5 10643.5 10934.0 10984.7 11007.9 11034.0 406733.0 415565.0 429798.0 436324.0 437704.0 78440.0 81372.0 81881.0 81988.2 82858.2 1085.3 1118.9 1134.5 1138.0 1148.6 16531.0 16862.0 16975.5 16984.2 17031.9 35477.9 37952.4 38131.2 38907.2 40228.8 49492.0 50322.7 50426.5 50794.5 51150.5 8434.7 8547.8 8611.1 8780.7 8959.7 10675.5 12205.5 12798.0 13261.1 13383.7 11404.6 11673.4 11883.2 12051.9 12379.3 6517.7 11464.0 11676.4 12165.1 12282.3 11768.1 11971.1 12017.5 12068.2 12361.1 8854.0 8928.9 8992.7 9054.7 9172.2 9439.2 11837.1 12131.0 12186.3 12591.7 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#3] mootools.html Cycles:180 Average:6043.00 Median:471.82 stddev:3451.31 (731.5%) stddev-sans-first:3443.34 19:46:30 INFO - PROCESS | 16885 | Values: 1397.5 1557.5 1587.6 1779.0 1907.4 8551.8 8934.2 8937.4 9243.6 9259.6 8985.1 9213.5 9216.4 9242.9 9252.8 2107.2 2336.8 2383.5 2408.5 2446.2 2438.4 2459.0 2463.0 2468.7 2469.3 2425.5 2438.8 2447.6 2448.2 2451.5 2062.8 2353.4 2402.4 2432.6 2447.8 2356.2 2362.1 2368.2 2369.4 2402.2 9092.7 9204.4 9223.0 9226.1 9266.0 5469.7 5637.9 5639.3 5647.4 5660.1 3505.9 3508.4 3598.6 3666.9 3674.9 1110.9 1119.8 1130.3 1152.8 1214.1 12932.2 12935.5 12953.9 12960.0 12962.5 4932.2 4932.8 5031.0 5039.8 5051.3 10723.8 10735.1 10768.2 10772.2 10783.0 4876.4 5553.0 5633.4 5656.5 5668.7 4985.3 5145.5 5171.3 5202.2 5207.9 4134.7 4154.4 4195.3 4218.1 4238.6 1252.5 1267.2 1285.7 1293.0 1294.2 928.6 967.2 1039.8 1044.8 1065.7 6324.1 6417.3 6443.7 6471.5 6530.5 458.3 470.6 470.8 472.9 480.8 4377.9 4424.6 4511.5 4515.0 4533.0 11123.0 11614.5 11730.1 11744.6 11753.1 11409.0 11444.2 11458.9 11558.8 11573.6 8208.0 8642.6 8664.4 8784.9 8853.5 6231.8 6439.3 6475.8 6523.2 6547.2 10229.8 10342.7 10343.4 10405.1 10425.9 10325.9 10599.5 10649.7 10726.0 10761.7 7328.8 7555.3 7558.7 8179.7 8327.3 8236.5 8315.0 8517.3 8561.5 8784.0 7631.8 7805.2 7808.0 7891.4 7950.5 7554.9 7783.1 7839.7 7916.0 7942.4 7067.0 7074.8 7078.7 7087.4 7118.4 5289.1 5401.6 5531.7 5559.9 5624.4 5418.6 5420.2 5459.8 5508.1 5562.6 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#4] prototype.html Cycles:180 Average:4535.23 Median:5207.39 stddev:1316.19 (25.3%) stddev-sans-first:1281.51 19:46:30 INFO - PROCESS | 16885 | Values: 331.4 333.8 337.8 339.8 340.2 5055.8 5159.9 5273.6 5308.2 5317.9 5153.5 5175.6 5201.4 5224.9 5227.0 3886.5 3929.6 4065.0 4069.0 4082.5 4044.9 4055.2 4061.9 4069.0 4096.2 3826.8 4044.3 4066.4 4086.9 4093.6 3917.0 4044.4 4047.0 4061.1 4063.7 3557.2 3565.4 3576.9 3581.3 3582.7 4911.8 4946.8 4954.8 4982.0 5029.3 4475.2 4568.3 4575.9 4590.8 4609.9 5278.9 5303.5 5305.4 5321.6 5330.3 3847.6 3865.6 3874.3 3881.1 3891.9 5377.4 5476.2 5536.6 5571.2 5612.3 4012.1 4035.3 4051.6 4056.2 4071.7 4090.9 4095.2 4098.9 4108.1 4110.0 4404.8 4613.0 4617.1 4641.0 4647.0 5405.2 5418.5 5425.3 5435.1 5442.8 1357.8 1376.7 1382.6 1396.7 1399.6 880.6 887.9 936.4 947.5 963.1 5187.4 5189.7 5207.3 5207.8 5224.7 5460.6 5501.2 5521.9 5552.8 5552.9 5066.5 5156.8 5200.5 5202.3 5207.5 2876.0 2911.3 2915.2 2929.0 2938.8 5432.9 5639.5 5651.0 5654.5 5681.6 5550.2 5554.1 5558.7 5563.7 5569.0 5626.2 5626.9 5653.7 5695.2 5704.6 5314.1 5438.7 5443.3 5457.9 5460.7 5421.9 5439.9 5451.7 5466.4 5467.3 5669.5 5675.5 5679.4 5688.3 5756.8 3846.4 3906.5 3992.1 4016.4 4017.2 5441.9 5473.5 5481.0 5497.0 5524.1 5314.4 5338.3 5340.2 5369.1 5375.5 5141.5 5291.6 5297.4 5307.5 5361.4 5072.4 5133.6 5192.9 5216.7 5226.1 5361.4 5364.7 5372.9 5383.4 5417.7 5283.6 5301.5 5307.2 5324.8 5364.0 19:46:30 INFO - PROCESS | 16885 | 19:46:30 INFO - PROCESS | 16885 | [#5] yui.html Cycles:180 Average:16012.64 Median:54064.00 stddev:19533.69 (36.1%) stddev-sans-first:19554.86 19:46:30 INFO - PROCESS | 16885 | Values: 749.3 849.5 862.4 863.2 869.4 14212.4 15215.0 15232.4 15348.5 15496.2 14261.9 14324.2 14354.0 14355.2 14436.4 668.9 787.2 818.0 819.1 821.7 825.4 827.0 827.8 828.0 834.1 725.1 875.6 875.8 876.8 879.9 865.6 877.8 879.3 883.2 885.8 640.0 640.9 642.1 645.7 647.2 14528.3 14542.2 14571.3 14593.2 14598.5 13898.9 13901.5 13996.7 14123.6 14145.2 11920.1 12004.6 12134.7 12257.4 12429.8 4453.0 4486.6 4511.8 4567.7 4650.2 57121.1 57790.1 58004.0 58237.7 59322.0 11295.9 11346.7 11462.4 11481.7 11658.4 20619.2 20638.5 20640.4 20650.6 20666.0 8376.9 8550.4 8675.0 8691.7 8829.4 4794.4 4836.3 4862.3 4895.8 4931.7 3486.4 3560.8 3583.3 3590.9 3596.2 502.4 655.8 684.3 687.9 707.3 717.2 766.9 780.6 786.9 788.1 9305.9 9700.4 9744.9 9745.6 9767.8 727.2 741.7 760.5 775.1 779.9 4542.3 4557.7 4618.0 4810.4 4828.8 69789.3 70134.3 71995.3 73371.3 73922.0 63265.7 63677.4 63981.7 64667.0 65929.0 55729.0 56711.4 57693.7 57817.7 58826.0 9207.0 9285.9 9439.7 9506.3 9587.7 37479.3 38061.8 38344.8 38758.0 38823.6 47444.0 51961.5 52000.2 52241.5 52399.0 11151.9 11327.9 12356.9 12365.3 12522.7 12550.1 12651.7 13085.3 13165.3 13288.5 12134.8 12165.3 12318.4 12434.2 12640.8 11928.2 12036.3 12169.1 12405.4 12800.0 11695.7 11838.3 11999.2 12114.9 12549.0 9439.2 9506.5 9646.6 9688.5 9994.7 9340.4 9461.8 9687.9 9699.7 9741.2 19:46:30 INFO - PROCESS | 16885 | -------- Summary: end -------- 19:46:30 INFO - PROCESS | 16885 | 19:46:31 INFO - TEST-INFO | 16885: exit 0 19:46:31 INFO - TEST-OK | dromaeo_css | took 1111315ms 19:46:31 INFO - TEST-START | kraken 19:46:31 INFO - operating with platform_type : linux_ 19:46:31 INFO - Initialising browser for kraken test... 19:46:31 INFO - TEST-INFO | started process 17071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL6ZF3Y/profile http://localhost:47873/getInfo.html) 19:46:35 INFO - PROCESS | 17071 | __metrics Screen width/height:1600/1200 19:46:35 INFO - PROCESS | 17071 | colorDepth:24 19:46:35 INFO - PROCESS | 17071 | Browser inner width/height: 1024/697 19:46:35 INFO - PROCESS | 17071 | __metrics 19:46:36 ERROR - PROCESS | 17071 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:46:37 INFO - TEST-INFO | 17071: exit 0 19:46:37 INFO - Browser initialized. 19:46:37 INFO - Running cycle 1/1 for kraken test... 19:46:37 INFO - TEST-INFO | started process 17153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL6ZF3Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:48 INFO - PROCESS | 17153 | RSS: Main: 150990848 19:46:48 INFO - PROCESS | 17153 | 19:46:51 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47873/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:51 INFO - PROCESS | 17153 | RSS: Main: 207896576 19:46:51 INFO - PROCESS | 17153 | 19:46:54 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47873/tests/kraken/driver.html?testName=audio-dft) 19:46:55 INFO - PROCESS | 17153 | RSS: Main: 196452352 19:46:55 INFO - PROCESS | 17153 | 19:46:58 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47873/tests/kraken/driver.html?testName=audio-fft) 19:46:58 INFO - PROCESS | 17153 | RSS: Main: 263753728 19:46:58 INFO - PROCESS | 17153 | 19:47:01 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47873/tests/kraken/driver.html?testName=audio-oscillator) 19:47:01 INFO - PROCESS | 17153 | RSS: Main: 173359104 19:47:01 INFO - PROCESS | 17153 | 19:47:03 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47873/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:47:04 INFO - PROCESS | 17153 | RSS: Main: 177938432 19:47:04 INFO - PROCESS | 17153 | 19:47:07 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47873/tests/kraken/driver.html?testName=imaging-darkroom) 19:47:08 INFO - PROCESS | 17153 | RSS: Main: 213643264 19:47:08 INFO - PROCESS | 17153 | 19:47:11 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47873/tests/kraken/driver.html?testName=imaging-desaturate) 19:47:12 INFO - PROCESS | 17153 | RSS: Main: 211365888 19:47:12 INFO - PROCESS | 17153 | 19:47:15 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47873/tests/kraken/driver.html?testName=json-parse-financial) 19:47:16 INFO - PROCESS | 17153 | RSS: Main: 212934656 19:47:16 INFO - PROCESS | 17153 | 19:47:17 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47873/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:47:18 INFO - PROCESS | 17153 | RSS: Main: 200597504 19:47:18 INFO - PROCESS | 17153 | 19:47:19 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:47:20 INFO - PROCESS | 17153 | RSS: Main: 204611584 19:47:20 INFO - PROCESS | 17153 | 19:47:22 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:47:23 INFO - PROCESS | 17153 | RSS: Main: 214945792 19:47:23 INFO - PROCESS | 17153 | 19:47:26 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:47:26 INFO - PROCESS | 17153 | RSS: Main: 222646272 19:47:26 INFO - PROCESS | 17153 | 19:47:29 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:47:30 INFO - PROCESS | 17153 | RSS: Main: 217571328 19:47:30 INFO - PROCESS | 17153 | 19:47:31 INFO - PROCESS | 17153 | Cycle 1(1): loaded http://localhost:47873/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47873/tests/kraken/driver.html?testName=ai-astar) 19:47:32 INFO - PROCESS | 17153 | RSS: Main: 263688192 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | __start_tp_report 19:47:32 INFO - PROCESS | 17153 | _x_x_mozilla_page_load 19:47:32 INFO - PROCESS | 17153 | _x_x_mozilla_page_load_details 19:47:32 INFO - PROCESS | 17153 | |i|pagename|runs| 19:47:32 INFO - PROCESS | 17153 | |0;ai-astar;113;108;115;116;112;112;114;113;113;116 19:47:32 INFO - PROCESS | 17153 | |1;audio-beat-detection;156;136;152;205;132;153;157;131;179;128 19:47:32 INFO - PROCESS | 17153 | |2;audio-dft;162;163;163;163;163;163;163;164;162;163 19:47:32 INFO - PROCESS | 17153 | |3;audio-fft;76;79;77;141;76;76;77;76;132;76 19:47:32 INFO - PROCESS | 17153 | |4;audio-oscillator;94;93;144;93;94;93;122;94;95;119 19:47:32 INFO - PROCESS | 17153 | |5;imaging-gaussian-blur;119;118;119;118;119;118;119;117;118;118 19:47:32 INFO - PROCESS | 17153 | |6;imaging-darkroom;149;148;148;147;148;148;149;150;148;148 19:47:32 INFO - PROCESS | 17153 | |7;imaging-desaturate;97;96;97;96;98;97;96;98;97;96 19:47:32 INFO - PROCESS | 17153 | |8;json-parse-financial;55;56;56;55;56;55;55;107;53;53 19:47:32 INFO - PROCESS | 17153 | |9;json-stringify-tinderbox;58;58;58;59;59;60;59;59;58;59 19:47:32 INFO - PROCESS | 17153 | |10;stanford-crypto-aes;81;84;83;80;81;82;83;86;86;83 19:47:32 INFO - PROCESS | 17153 | |11;stanford-crypto-ccm;140;141;126;141;133;142;129;133;213;137 19:47:32 INFO - PROCESS | 17153 | |12;stanford-crypto-pbkdf2;164;170;165;169;167;170;168;165;174;167 19:47:32 INFO - PROCESS | 17153 | |13;stanford-crypto-sha256-iterative;66;65;66;65;64;64;66;64;68;64 19:47:32 INFO - PROCESS | 17153 | __end_tp_report 19:47:32 INFO - PROCESS | 17153 | __start_cc_report 19:47:32 INFO - PROCESS | 17153 | _x_x_mozilla_cycle_collect,1707 19:47:32 INFO - PROCESS | 17153 | __end_cc_report 19:47:32 INFO - PROCESS | 17153 | __startTimestamp1463107652048__endTimestamp 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | ------- Summary: start ------- 19:47:32 INFO - PROCESS | 17153 | Number of tests: 14 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:2.35 (2.1%) stddev-sans-first:2.49 19:47:32 INFO - PROCESS | 17153 | Values: 113.0 108.0 115.0 116.0 112.0 112.0 114.0 113.0 113.0 116.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#1] audio-beat-detection Cycles:10 Average:152.90 Median:154.50 stddev:24.14 (15.6%) stddev-sans-first:25.58 19:47:32 INFO - PROCESS | 17153 | Values: 156.0 136.0 152.0 205.0 132.0 153.0 157.0 131.0 179.0 128.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:0.57 (0.3%) stddev-sans-first:0.50 19:47:32 INFO - PROCESS | 17153 | Values: 162.0 163.0 163.0 163.0 163.0 163.0 163.0 164.0 162.0 163.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#3] audio-fft Cycles:10 Average:88.60 Median:76.00 stddev:25.35 (33.4%) stddev-sans-first:26.48 19:47:32 INFO - PROCESS | 17153 | Values: 76.0 79.0 77.0 141.0 76.0 76.0 77.0 76.0 132.0 76.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:93.50 stddev:17.93 (19.2%) stddev-sans-first:18.64 19:47:32 INFO - PROCESS | 17153 | Values: 94.0 93.0 144.0 93.0 94.0 93.0 122.0 94.0 95.0 119.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 19:47:32 INFO - PROCESS | 17153 | Values: 119.0 118.0 119.0 118.0 119.0 118.0 119.0 117.0 118.0 118.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 19:47:32 INFO - PROCESS | 17153 | Values: 149.0 148.0 148.0 147.0 148.0 148.0 149.0 150.0 148.0 148.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 19:47:32 INFO - PROCESS | 17153 | Values: 97.0 96.0 97.0 96.0 98.0 97.0 96.0 98.0 97.0 96.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:55.00 stddev:16.52 (30.0%) stddev-sans-first:17.41 19:47:32 INFO - PROCESS | 17153 | Values: 55.0 56.0 56.0 55.0 56.0 55.0 55.0 107.0 53.0 53.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 19:47:32 INFO - PROCESS | 17153 | Values: 58.0 58.0 58.0 59.0 59.0 60.0 59.0 59.0 58.0 59.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:2.02 (2.4%) stddev-sans-first:2.03 19:47:32 INFO - PROCESS | 17153 | Values: 81.0 84.0 83.0 80.0 81.0 82.0 83.0 86.0 86.0 83.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:140.50 stddev:25.03 (17.8%) stddev-sans-first:26.51 19:47:32 INFO - PROCESS | 17153 | Values: 140.0 141.0 126.0 141.0 133.0 142.0 129.0 133.0 213.0 137.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.50 stddev:3.00 (1.8%) stddev-sans-first:2.83 19:47:32 INFO - PROCESS | 17153 | Values: 164.0 170.0 165.0 169.0 167.0 170.0 168.0 165.0 174.0 167.0 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - PROCESS | 17153 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.32 (2.0%) stddev-sans-first:1.36 19:47:32 INFO - PROCESS | 17153 | Values: 66.0 65.0 66.0 65.0 64.0 64.0 66.0 64.0 68.0 64.0 19:47:32 INFO - PROCESS | 17153 | -------- Summary: end -------- 19:47:32 INFO - PROCESS | 17153 | 19:47:32 INFO - TEST-INFO | 17153: exit 0 19:47:32 INFO - TEST-OK | kraken | took 61568ms 19:47:32 INFO - SUITE-END | took 1172s 19:47:33 INFO - Completed test suite (00:19:33) 19:47:33 INFO - javascript benchmark 19:47:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.0228627982187, 3111.3155105876713, 3142.9528557071635, 3146.496560550311, 3187.9840600796992, 5182.17085266357, 5190.195519694444, 5301.7879284828605, 5334.759935802888, 5355.116405793045, 5311.707856067915, 5314.5216930476345, 5324.866878328044, 5364.195370694388, 5461.290032295806, 4798.712077275357, 4832.73419961902, 4894.388201474816, 4929.65492415531, 4988.0, 4860.05228980349, 4896.632752543555, 4913.631477639174, 4921.2618107284015, 4931.358923339961, 4669.019505903764, 4675.2052151134385, 4698.154332220199, 4720.693154944935, 4765.666403351766, 4736.407949006374, 4829.758512074382, 4840.0, 4852.781624826856, 4875.853724388274, 4649.442066951953, 4758.02460495601, 4789.568938795525, 4796.664233503656, 4834.178189707758, 5219.478052194788, 5291.312129423189, 5295.867603309948, 5308.256844041837, 5326.360836699582, 5662.688552129631, 5692.0, 5696.458836410535, 5703.287089113861, 5714.028615135437, 5510.586705997026, 5569.470900264469, 5579.414161513064, 5735.283089613798, 5771.740271687784, 5300.708461034642, 5317.654352467077, 5345.786168553292, 5349.358077030743, 5356.812511562116, 5775.768969241268, 5801.216835727208, 5807.593468457168, 5810.535157187415, 5823.010088285002, 5372.892542149135, 5499.340079190442, 5549.223108764776, 5560.610757246954, 5615.157726341, 5692.345380281238, 5696.487316141484, 5704.828855134353, 5709.571782116358, 5711.942880571224, 5643.125315576092, 5678.77284908599, 5682.119271512839, 5684.37471878093, 5688.601797874192, 5442.455754424526, 5550.91673624896, 5623.2408624835125, 5634.23937768396, 5674.943250567441, 4277.593628605277, 4311.1808756336195, 4313.374560688745, 4314.611684948307, 4349.673774466864, 3785.413260944558, 3860.575336712959, 3872.9736619795776, 3888.475055867422, 3912.374020156761, 5299.788008479618, 5358.356997160287, 5371.811986580451, 5403.405625381205, 5403.459654034644, 5704.942950570441, 5705.058665320175, 5710.057840456361, 5719.142128680731, 5730.885382292414, 5190.870228244324, 5285.762140703678, 5365.007473617393, 5405.405405405402, 5458.4814442627885, 4865.95134048655, 4920.8769780755765, 4948.703077815343, 4949.925751113737, 4955.801767929314, 5954.642721436728, 5970.402959703995, 5970.432808883149, 5970.731317090741, 5976.5218782497295, 5419.430959749169, 5597.720113994203, 5614.943850561442, 5752.942470575241, 5784.2769653793275, 5866.823995280149, 5882.382349853375, 5887.852803679943, 5890.410958904075, 5897.823065308047, 5572.832815015556, 5573.804916828054, 5575.721213939368, 5575.972120139373, 5577.107662773937, 5505.779768809363, 5651.463111004448, 5684.8010319638615, 5706.828795136153, 5724.0, 5629.902169077154, 5632.183333416713, 5633.605647604629, 5644.8024319148635, 5660.0, 5522.779088836402, 5559.722013899208, 5566.081596536526, 5567.7494512747835, 5578.832635020955, 5258.106121959199, 5436.048691478896, 5484.040292948798, 5527.198556209408, 5563.13771365439, 5662.660240385425, 5672.744726487235, 5698.515626171822, 5729.9713501432225, 5736.42635736423, 5288.947110528846, 5527.0, 5527.5577953764405, 5534.806281780279, 5594.636348637326], "value": 5238.466650028261, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7647564498557, 593.1458699472759, 603.131490653459, 604.2175382879171, 606.31486420345, 32329.353412931756, 36303.81848090759, 36309.45535816962, 36313.27373452536, 43974.340384894174, 42808.3578746319, 47530.287045694255, 47656.2851557226, 47866.76066619671, 47874.0, 8779.78050548735, 8952.552372381388, 8964.910350896473, 8966.86549701755, 8974.237189838872, 8804.295656347509, 8922.687705930293, 8946.955265223665, 8947.865782013243, 8973.371863969525, 8949.95525022374, 8965.0, 8994.460332380078, 9005.459672419644, 9023.548822558878, 8957.95521022394, 8966.282697384193, 9005.234555062827, 9008.144226298491, 9016.323775716848, 8730.432521886058, 8831.470111793249, 8835.69075082369, 8843.734687959372, 8866.911330886673, 42767.78616106931, 43010.78494607538, 43024.13951720979, 43028.0, 43131.56868431307, 3593.2386046140473, 3868.845246190149, 4258.26226278028, 4324.048709283952, 4374.868753937387, 32909.17727056819, 33061.83469082663, 33098.3380332392, 33456.832715836266, 34628.653713462794, 4062.2173810939603, 4102.774347410891, 4172.123853990665, 4196.769177695225, 4262.46719160105, 349461.0, 351355.0, 353039.0, 356085.2195739031, 357533.0, 32279.838600806845, 32950.176245594055, 61865.690671546356, 62310.68844655748, 62848.37151628425, 38124.23751524954, 38149.427758583646, 38245.23509529849, 38286.61713382886, 38565.42151867724, 14294.0, 14344.641383965485, 14363.928180359031, 14373.0, 14433.20617366055, 11520.0, 11585.189036767513, 11602.07183425324, 11611.129165312635, 11655.825162622568, 8621.965364156216, 8641.697540586048, 8657.913420865836, 8673.869891951626, 8744.169303916118, 3370.5281260623533, 3382.915427114342, 3384.983075084658, 3387.3902697514686, 3394.0835974287297, 1041.1571518412368, 1129.0628778114146, 1141.748815260658, 1145.7479354541988, 1148.2019996102795, 33121.50317745236, 33373.83313083419, 33430.8328458361, 33710.157246069044, 34361.14097147541, 1078.3368228539548, 1107.789519991199, 1162.5872815150499, 1188.369531082551, 1214.3909320150835, 17397.47807565775, 17778.37775677845, 17805.554861128574, 17970.91014544919, 17976.730349044774, 529937.0, 530011.0, 536804.0, 537258.0, 537398.0, 61481.0, 62141.378586214465, 62203.688981554806, 62206.0, 62316.688416558536, 1290.0324756432676, 1360.6870419803502, 1410.2102822419479, 1419.6734751007325, 1430.7496188167238, 32723.0, 34136.658633413346, 34290.14274643154, 34577.25743480098, 34814.47778283277, 50168.99662006812, 50584.0, 50585.747071264406, 50708.0, 50864.49135508598, 349856.0, 355751.3186511074, 356211.0, 357077.0, 358472.0, 25753.742462575134, 28174.85912570424, 28219.57670634983, 29085.709142909145, 29409.0, 46680.29979550242, 47259.0, 48733.0, 50699.0, 51206.0, 39777.60222397739, 42095.36856947087, 43593.346099807895, 44502.0, 45353.77323113364, 42342.36486452767, 43107.568924310355, 43115.0, 43330.0, 43704.78147609369, 41220.587794121675, 42430.151396972506, 42601.0, 43297.7835110835, 43371.783141084095, 35653.46519802257, 36692.44961325529, 37025.44461833128, 37485.625143749305, 38062.80968595139, 28626.14121576356, 28824.279393015342, 28848.0, 28974.565381519715, 29211.7078829209], "value": 19056.663061086198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.83165070670316, 410.3291119020401, 412.62863422919366, 419.8362638570957, 430.27928220231104, 13513.459461621524, 17540.649187016268, 19332.806671933293, 19385.0, 19625.607487850233, 14073.788893166586, 17129.314827406888, 17922.910385448053, 18197.90901045493, 19035.619287614238, 5701.372848986608, 5816.331121920991, 5859.589828711992, 5862.325832529271, 5904.173415721781, 5722.971385143069, 5779.104238842965, 5780.1907732917425, 5809.419058094178, 5846.941530584682, 4107.897302567445, 6137.938620613803, 6485.772997945073, 6487.3836985486305, 6604.801855944305, 6434.453071488929, 6531.020346947948, 6541.313162118005, 6552.180977377828, 6570.8028759137305, 5582.804601838957, 5721.4564616361795, 5738.971305143489, 5743.081107022898, 5756.7121643917635, 21715.782842171535, 21986.89006554973, 22048.0, 22352.21767238155, 22567.435814104614, 2409.506051259488, 2427.2354208424467, 2509.736477669855, 2875.6692980307325, 3161.1780936956325, 13496.797548036788, 13959.441622335095, 14161.0, 14224.35990380425, 14610.561683149523, 1927.9903600482048, 1935.94192174235, 1960.8529360298035, 1992.432156835295, 2007.437917383135, 83294.16705832924, 83648.58175709144, 83768.16231837665, 83969.0, 85307.0, 10229.0, 10354.0, 10667.19996000303, 10754.300970436896, 10862.131029517619, 14549.709005819825, 14665.92667036658, 14681.19253441071, 14721.411143554138, 14774.48289309869, 7567.886481702779, 7608.429367797326, 7640.617969101522, 7713.807154821062, 7729.5748733819055, 7307.926920730831, 7372.59450730215, 7403.518771279957, 7427.740029099064, 7458.843879198733, 5760.481556659921, 6112.052631842072, 6553.836154096186, 6590.769323073765, 6664.766733164316, 1952.4045166224437, 1954.101113487783, 1963.2539634938632, 1965.4398496428448, 1968.0750047477663, 1000.554753134848, 1029.7425643589104, 1149.36210403227, 1160.9071274298205, 1180.1561883253344, 16636.916815415847, 18664.34674786403, 18958.81041189598, 18993.145308460877, 19123.90438047829, 996.4220751963844, 1020.2195320579763, 1025.933314334566, 1047.4553232319151, 1071.49104175516, 10643.467826608794, 10934.026488426785, 10984.725381865357, 11007.889921100847, 11034.0, 406733.0, 415565.0, 429798.0, 436324.0, 437704.0, 78440.0, 81371.9780794096, 81881.0, 81988.18011819925, 82858.17141828504, 1085.3325204998869, 1118.927269727465, 1134.5235001299475, 1138.0497284767312, 1148.626696323698, 16531.01627804671, 16862.0, 16975.490735277963, 16984.23570939286, 17031.914840425718, 35477.93566193018, 37952.430713538764, 38131.237375252895, 38907.221855562166, 40228.79885600553, 49492.0, 50322.74838625784, 50426.49573504218, 50794.492055078976, 51150.48849511457, 8434.662613495391, 8547.786305342168, 8611.13888611134, 8780.692675756318, 8959.686410975584, 10675.466226688479, 12205.52142639379, 12798.0, 13261.138026028277, 13383.732325353243, 11404.600838970597, 11673.416329183337, 11883.168178227786, 12051.879481205426, 12379.257244565355, 6517.674116294261, 11464.0, 11676.416179190837, 12165.148439609142, 12282.324472154118, 11768.058555315383, 11971.10216733731, 12017.519299228283, 12068.155229133876, 12361.07291953089, 8854.026057133582, 8928.910710893068, 8992.685256016008, 9054.683086091954, 9172.174504294646, 9439.244860411289, 11837.112216583619, 12131.0, 12186.329751863732, 12591.685207869876], "value": 9301.498873726754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.5248415538715, 1557.4704600435853, 1587.5713557339518, 1778.9555261118471, 1907.4277716684999, 8551.828963420729, 8934.240589549896, 8937.419067760598, 9243.6302547898, 9259.583318750656, 8985.146411090936, 9213.493257870814, 9216.354855160142, 9242.861357079631, 9252.768680782967, 2107.1782005018053, 2336.836421450499, 2383.547126046046, 2408.506256217471, 2446.1683027770555, 2438.430620488178, 2459.0, 2462.9876850615724, 2468.7407822178684, 2469.320936742401, 2425.454272788626, 2438.792702620279, 2447.608382658775, 2448.167623008177, 2451.472933319336, 2062.8143467087957, 2353.376355265856, 2402.423418379594, 2432.6351047342864, 2447.779699827024, 2356.175338631482, 2362.14962613459, 2368.2184878990065, 2369.3839601703507, 2402.1592442644933, 9092.681756138503, 9204.355695101345, 9222.953885230598, 9226.123518265755, 9266.0, 5469.699166545838, 5637.8590535236535, 5639.323281206241, 5647.407022262644, 5660.122680984454, 3505.91235219119, 3508.4211105167615, 3598.586162591283, 3666.9083272918124, 3674.8530058797614, 1110.9277896936674, 1119.7704470583592, 1130.299214487015, 1152.7924973504767, 1214.1258294028285, 12932.224066555942, 12935.547255846095, 12953.87046129536, 12960.0, 12962.546310879168, 4932.235503496964, 4932.778024988884, 5030.974845125751, 5039.82360617384, 5051.292819005343, 10723.785524289626, 10735.087517561095, 10768.192385571114, 10772.192085593611, 10783.0, 4876.4392094909535, 5553.0, 5633.352164501053, 5656.519195868315, 5668.744906479218, 4985.252212168131, 5145.511176438233, 5171.3277273954145, 5202.167653175474, 5207.869803254949, 4134.689898257643, 4154.376843473503, 4195.30777421722, 4218.093109981369, 4238.639715624145, 1252.467701226975, 1267.163671976491, 1285.7235694325766, 1293.043148070421, 1294.249335385474, 928.5728564860104, 967.2068903499204, 1039.8336266197373, 1044.7858189071221, 1065.7495488560182, 6324.08300796382, 6417.326180750954, 6443.742250310029, 6471.546991710629, 6530.477561795045, 458.27363628648965, 470.60469205867236, 470.76226505614443, 472.87468820762524, 480.79566184371504, 4377.9124417511475, 4424.623906967944, 4511.5262897395305, 4515.029268707243, 4533.025399539115, 11123.0, 11614.477348519336, 11730.12024098196, 11744.647660570197, 11753.118516111326, 11409.0, 11444.198906076495, 11458.885411145782, 11558.826617600742, 11573.594924177782, 8208.015038195455, 8642.611082501302, 8664.393492455467, 8784.912150878537, 8853.51305678181, 6231.844203894939, 6439.291677915332, 6475.805725828232, 6523.217213934358, 6547.214334279917, 10229.84655230187, 10342.741431464274, 10343.431111288952, 10405.06354428105, 10425.9478702606, 10325.896741032493, 10599.47002649849, 10649.680509584725, 10726.0, 10761.730956726144, 7328.78013659591, 7555.320021198118, 7558.735444259424, 8179.67281308741, 8327.333813295043, 8236.464629799044, 8314.958425208077, 8517.276031537142, 8561.48631082137, 8784.0, 7631.809204769926, 7805.18045605226, 7807.960960195162, 7891.408144389079, 7950.522968621901, 7554.886676699744, 7783.104942931409, 7839.686412543434, 7916.010498687664, 7942.44402891792, 7066.964665176847, 7074.823129421806, 7078.68145933424, 7087.362137407658, 7118.359347658736, 5289.100852855101, 5401.567874570103, 5531.668099914018, 5559.888802223852, 5624.437556244343, 5418.566514678895, 5420.159875219348, 5459.836204913859, 5508.063629183129, 5562.582806289463], "value": 4747.79925773003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.4067818604698, 333.8063922924704, 337.81251405469953, 339.7757480063158, 340.15980528095594, 5055.772490237939, 5159.922601160981, 5273.604479664022, 5308.23030660554, 5317.9468205317935, 5153.510416510426, 5175.637705360627, 5201.375834899808, 5224.947750522494, 5227.032998895207, 3886.4947556817574, 3929.6266854648907, 4064.97967510162, 4069.0, 4082.530508991474, 4044.858429954952, 4055.1686904184717, 4061.918761624766, 4069.003094241915, 4096.180763847227, 3826.8086595670243, 4044.292248856453, 4066.4103704962763, 4086.9182616347657, 4093.564329224107, 3917.0, 4044.4135600337786, 4047.028713108862, 4061.1471590965934, 4063.7155399122075, 3557.181848174934, 3565.358235517606, 3576.9284614307567, 3581.3195492856275, 3582.677559019675, 4911.796609830614, 4946.752662366904, 4954.752262386866, 4981.978694367682, 5029.309319514043, 4475.239209334389, 4568.291914753218, 4575.9084818303445, 4590.793414296331, 4609.884752881171, 5278.9208161877605, 5303.496167864047, 5305.44292849253, 5321.600879933982, 5330.333708286465, 3847.5960024197334, 3865.613438656112, 3874.2832575973534, 3881.146147847469, 3891.8637847675477, 5377.354717433894, 5476.17857321405, 5536.612437129363, 5571.164325351229, 5612.270404847384, 4012.137390461065, 4035.29382358092, 4051.594840515984, 4056.249593825152, 4071.694622903294, 4090.897727556835, 4095.24238015968, 4098.897527561835, 4108.116754897652, 4109.97945010273, 4404.801783919731, 4613.0, 4617.053504031665, 4640.9535904640525, 4646.976765116153, 5405.21624364465, 5418.485243901823, 5425.26758887553, 5435.1303791393975, 5442.782288708487, 1357.8099066130749, 1376.6971266321393, 1382.6197795606288, 1396.6508372906774, 1399.6081097292774, 880.6213328268777, 887.9112088791198, 936.4147407870081, 947.4788866123605, 963.1139351796364, 5187.377514698261, 5189.662671926389, 5207.3336532309295, 5207.817726379559, 5224.686518808883, 5460.570046329425, 5501.229827824108, 5521.861953451196, 5552.805651802168, 5552.888942221133, 5066.518680725325, 5156.839711065056, 5200.479952004845, 5202.3497062867145, 5207.453217412193, 2875.9712402875703, 2911.344947386821, 2915.212892519008, 2929.0041385928885, 2938.7942844000713, 5432.91850622241, 5639.464250896158, 5650.97174514133, 5654.46282603152, 5681.57388195887, 5550.222968784374, 5554.08357620988, 5558.6942718150085, 5563.693996830132, 5568.972155139199, 5626.212330273765, 5626.943730562723, 5653.660780353192, 5695.180885461723, 5704.629199102045, 5314.096603577324, 5438.700871452108, 5443.339781366722, 5457.890842183214, 5460.6996615186545, 5421.91256366294, 5439.918401224064, 5451.700156491431, 5466.3713672928125, 5467.261919640798, 5669.5180909621495, 5675.517581005661, 5679.375268720355, 5688.288963879515, 5756.769729210785, 3846.3653497173773, 3906.5312162540677, 3992.0818211810865, 4016.4376987222395, 4017.2367250222364, 5441.918371224514, 5473.480019398151, 5480.972595136999, 5497.038018346693, 5524.06090964545, 5314.388845282688, 5338.332708411449, 5340.225667278145, 5369.087255166709, 5375.489328513785, 5141.53726164647, 5291.576673866004, 5297.364316282071, 5307.52232299095, 5361.35663720356, 5072.391313042311, 5133.5882632276425, 5192.870178245574, 5216.6697492139265, 5226.085435048927, 5361.418381577522, 5364.704941228114, 5372.919406208832, 5383.40782513931, 5417.729113544228, 5283.603729720363, 5301.496357845998, 5307.150855863043, 5324.760385782572, 5363.973180134074], "value": 4119.193548085515, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.3031480722927, 849.5285116760198, 862.3963225741982, 863.1996792698442, 869.3610196505571, 14212.360443780042, 15215.0, 15232.39070437184, 15348.539543813675, 15496.225188740545, 14261.930355223349, 14324.212168330738, 14354.0, 14355.210463424508, 14436.350364233609, 668.8896332105209, 787.1970589998199, 817.9631916563769, 819.0785366462729, 821.7041864928651, 825.3809642767924, 826.9669213231464, 827.7971896885272, 827.9710210142646, 834.0866750907777, 725.0646665801111, 875.5928493250638, 875.8029443375221, 876.7545087375513, 879.9032106468284, 865.5974971638187, 877.7981064355203, 879.2702057292496, 883.1786438612088, 885.7564169853277, 640.0303540136697, 640.903864420336, 642.0754114075716, 645.6771614192903, 647.2459584583954, 14528.273586320642, 14542.200178990151, 14571.324297705794, 14593.197374144418, 14598.489052883098, 13898.93050534751, 13901.513447029305, 13996.720065598733, 14123.576292711236, 14145.151290922578, 11920.105992050545, 12004.639860804102, 12134.696632584168, 12257.448414821265, 12429.813552796715, 4453.0, 4486.618637415823, 4511.751853648048, 4567.703099298535, 4650.18621741194, 57121.14318285229, 57790.13314800282, 58004.0, 58237.70881145567, 59322.0, 11295.887041129648, 11346.716332091599, 11462.369569673581, 11481.655550333504, 11658.358790266617, 20619.175232990514, 20638.484037899172, 20640.380788576367, 20650.58698826015, 20666.0, 8376.874346884802, 8550.401471897032, 8675.045744968049, 8691.739247822576, 8829.426087304304, 4794.400699912511, 4836.347093142451, 4862.343583616166, 4895.755212239445, 4931.679440836334, 3486.38988177068, 3560.750747447654, 3583.3191693578137, 3590.856365745341, 3596.17288023756, 502.37705245495323, 655.8360409897525, 684.2644157530674, 687.9105716256854, 707.2856415020763, 717.1680850213727, 766.8734658781239, 780.5706861226303, 786.9016372953381, 788.0897563314381, 9305.860412093823, 9700.369475984038, 9744.90255097454, 9745.610175592898, 9767.755806104904, 727.2328242370984, 741.6810771368364, 760.5396542186023, 775.0853992289153, 779.8830175473724, 4542.250528662799, 4557.749323787223, 4617.953820461819, 4810.350602668665, 4828.758562071883, 69789.30210697828, 70134.2986570138, 71995.28004719886, 73371.26628733751, 73922.0, 63265.68367158135, 63677.363226367146, 63981.680091600174, 64667.0, 65929.0, 55729.0, 56711.43288567144, 57693.71153144291, 57817.710911445174, 58826.0, 9206.953965230132, 9285.86071208946, 9439.669611563562, 9506.33290116674, 9587.664431744855, 37479.25041499209, 38061.80969095137, 38344.80827595844, 38758.0, 38823.611763882, 47444.0, 51961.48038519567, 52000.21999669932, 52241.47758522366, 52399.0, 11151.888481115086, 11327.943360283145, 12356.938215308866, 12365.258084514959, 12522.747725227404, 12550.064111767515, 12651.746965060463, 13085.280309583064, 13165.275909824668, 13288.534901278408, 12134.817977730518, 12165.270083795001, 12318.384080796104, 12434.191777534432, 12640.810387844374, 11928.224665396721, 12036.338001410006, 12169.148159628741, 12405.44175512086, 12800.0, 11695.70862205333, 11838.28970261787, 11999.220050696676, 12114.878851211375, 12549.0, 9439.150476457151, 9506.524673766145, 9646.614135434786, 9688.515574221401, 9994.700158995242, 9340.392874463138, 9461.810763784548, 9687.9031209687, 9699.66051188205, 9741.172000379764], "value": 7013.389376417197, "unit": "score"}], "name": "dromaeo_css", "value": 7093.273732616199, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 115.0, 116.0, 112.0, 112.0, 114.0, 113.0, 113.0, 116.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 136.0, 152.0, 205.0, 132.0, 153.0, 157.0, 131.0, 179.0, 128.0], "value": 152.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 163.0, 163.0, 163.0, 163.0, 164.0, 162.0, 163.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 77.0, 141.0, 76.0, 76.0, 77.0, 76.0, 132.0, 76.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 144.0, 93.0, 94.0, 93.0, 122.0, 94.0, 95.0, 119.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 119.0, 118.0, 119.0, 118.0, 119.0, 117.0, 118.0, 118.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 148.0, 148.0, 149.0, 150.0, 148.0, 148.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 98.0, 97.0, 96.0, 98.0, 97.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 56.0, 55.0, 55.0, 107.0, 53.0, 53.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 59.0, 60.0, 59.0, 59.0, 58.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 80.0, 81.0, 82.0, 83.0, 86.0, 86.0, 83.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 126.0, 141.0, 133.0, 142.0, 129.0, 133.0, 213.0, 137.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 165.0, 169.0, 167.0, 170.0, 168.0, 165.0, 174.0, 167.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 64.0, 64.0, 66.0, 64.0, 68.0, 64.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1563.4, "alertThreshold": 2.0}]} 19:47:33 INFO - Return code: 0 19:47:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:47:33 ERROR - # TBPL SUCCESS # 19:47:33 INFO - Running post-action listener: _resource_record_post_action 19:47:33 INFO - ##### 19:47:33 INFO - ##### Finished run-tests step (success) 19:47:33 INFO - ##### 19:47:33 INFO - Running post-run listener: _resource_record_post_run 19:47:34 INFO - Total resource usage - Wall time: 1191s; CPU: 26.0%; Read bytes: 4096; Write bytes: 550510592; Read time: 16; Write time: 544872 19:47:34 INFO - TinderboxPrint: CPU usage
25.5% 19:47:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:47:34 INFO - TinderboxPrint: I/O write bytes / time
550,510,592 / 544,872 19:47:34 INFO - TinderboxPrint: CPU idle
7,080.3 (74.5%) 19:47:34 INFO - TinderboxPrint: CPU user
2,387.8 (25.1%) 19:47:34 INFO - TinderboxPrint: Swap in / out
0 / 0 19:47:34 INFO - install - Wall time: 17s; CPU: 27.0%; Read bytes: 0; Write bytes: 260796416; Read time: 0; Write time: 301592 19:47:34 INFO - run-tests - Wall time: 1174s; CPU: 25.0%; Read bytes: 4096; Write bytes: 289116160; Read time: 16; Write time: 243280 19:47:34 INFO - Running post-run listener: _upload_blobber_files 19:47:34 INFO - Blob upload gear active. 19:47:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:34 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 19:47:34 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:47:34 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:47:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:47:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:47:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:35 INFO - (blobuploader) - INFO - Done attempting. 19:47:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:47:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:36 INFO - (blobuploader) - INFO - Done attempting. 19:47:36 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:36 INFO - Return code: 0 19:47:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf9a635d081ecc20f65bf050cd6d0c10d8f60b148a0cacab1f3477f2e2dfd89da30f3ff830b7ae369d5397daa901e0e4973d18e8bf2ef914847fa143fd61f853", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5648197611e85a8ce5d88b9ca0cf141a9f396409f95c0f25df0664695d3712064d1cc51ba3607ccfbb268cbbb1058d8b4d2adc28ad22820bd1a14d0c8dfa9da9"} 19:47:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:36 INFO - Contents: 19:47:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf9a635d081ecc20f65bf050cd6d0c10d8f60b148a0cacab1f3477f2e2dfd89da30f3ff830b7ae369d5397daa901e0e4973d18e8bf2ef914847fa143fd61f853", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5648197611e85a8ce5d88b9ca0cf141a9f396409f95c0f25df0664695d3712064d1cc51ba3607ccfbb268cbbb1058d8b4d2adc28ad22820bd1a14d0c8dfa9da9"} 19:47:36 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:36 INFO - Copying logs to upload dir... 19:47:36 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.215383 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-12 19:47:35.228594) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 19:47:35.232041) ========= 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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf9a635d081ecc20f65bf050cd6d0c10d8f60b148a0cacab1f3477f2e2dfd89da30f3ff830b7ae369d5397daa901e0e4973d18e8bf2ef914847fa143fd61f853", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5648197611e85a8ce5d88b9ca0cf141a9f396409f95c0f25df0664695d3712064d1cc51ba3607ccfbb268cbbb1058d8b4d2adc28ad22820bd1a14d0c8dfa9da9"} build_url:https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005311 build_url: 'https://queue.taskcluster.net/v1/task/I14vp9ZaTdyVZ-F_jard4g/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/Mozilla-Inbound/sha512/cf9a635d081ecc20f65bf050cd6d0c10d8f60b148a0cacab1f3477f2e2dfd89da30f3ff830b7ae369d5397daa901e0e4973d18e8bf2ef914847fa143fd61f853", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5648197611e85a8ce5d88b9ca0cf141a9f396409f95c0f25df0664695d3712064d1cc51ba3607ccfbb268cbbb1058d8b4d2adc28ad22820bd1a14d0c8dfa9da9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 19:47:35.247220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:47:35.247636) ========= 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-1463105711.53244-2086596609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002099 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:47:35.326343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 19:47:35.326707) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 19:47:35.327037) ========= ========= Total master_lag: 0.16 =========