builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1467646681.97 results: success (0) buildid: 20160704060523 builduid: a650a638d545412a94d9f8e7a17ca06e revision: 3a78197e37b6ffaa41aa426f4a7d8479c061febc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:01.974774) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:01.975239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:01.975535) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:01.995818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:01.996161) ========= 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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-04 08:38:02-- 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% 112M=0s 2016-07-04 08:38:02 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115453 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.144783) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.145226) ========= 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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016809 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.197067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.197684) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a78197e37b6ffaa41aa426f4a7d8479c061febc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a78197e37b6ffaa41aa426f4a7d8479c061febc --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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-04 08:38:02,511 truncating revision to first 12 chars 2016-07-04 08:38:02,511 Setting DEBUG logging. 2016-07-04 08:38:02,511 attempt 1/10 2016-07-04 08:38:02,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a78197e37b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 08:38:02,809 unpacking tar archive at: mozilla-aurora-3a78197e37b6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.672196 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.884777) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.885082) ========= script_repo_revision: 3a78197e37b6ffaa41aa426f4a7d8479c061febc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.885490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.885802) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 08:38:02.895322) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-04 08:38:02.895699) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:38:03 INFO - MultiFileLogger online at 20160704 08:38:03 in /builds/slave/test 08:38:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:38:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:38:03 INFO - {'append_to_log': False, 08:38:03 INFO - 'base_work_dir': '/builds/slave/test', 08:38:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:38:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:38:03 INFO - 'branch': 'Mozilla-Aurora', 08:38:03 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:03 INFO - 'config_files': ('talos/linux_config.py',), 08:38:03 INFO - 'default_actions': ('clobber', 08:38:03 INFO - 'read-buildbot-config', 08:38:03 INFO - 'download-and-extract', 08:38:03 INFO - 'populate-webroot', 08:38:03 INFO - 'create-virtualenv', 08:38:03 INFO - 'install', 08:38:03 INFO - 'run-tests'), 08:38:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:03 INFO - 'download_minidump_stackwalk': True, 08:38:03 INFO - 'download_symbols': 'ondemand', 08:38:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:38:03 INFO - 'tooltool.py': '/tools/tooltool.py', 08:38:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:38:03 INFO - '/tools/misc-python/virtualenv.py')}, 08:38:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:03 INFO - 'installer_path': 'installer.exe', 08:38:03 INFO - 'log_level': 'info', 08:38:03 INFO - 'log_name': 'talos', 08:38:03 INFO - 'log_to_console': True, 08:38:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:38:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:38:03 INFO - 'opt_config_files': (), 08:38:03 INFO - 'pip_index': False, 08:38:03 INFO - 'sps_profile': False, 08:38:03 INFO - 'sps_profile_interval': 0, 08:38:03 INFO - 'suite': 'dromaeojs', 08:38:03 INFO - 'system_bits': '32', 08:38:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:03 INFO - 'title': 'talos-linux64-ix-007', 08:38:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:03 INFO - 'use_talos_json': True, 08:38:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:38:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:03 INFO - 'work_dir': 'build'} 08:38:03 INFO - ##### 08:38:03 INFO - ##### Running clobber step. 08:38:03 INFO - ##### 08:38:03 INFO - Running pre-action listener: _resource_record_pre_action 08:38:03 INFO - Running main action method: clobber 08:38:03 INFO - rmtree: /builds/slave/test/build 08:38:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:38:03 INFO - Running post-action listener: _resource_record_post_action 08:38:03 INFO - ##### 08:38:03 INFO - ##### Finished clobber step (success) 08:38:03 INFO - ##### 08:38:03 INFO - ##### 08:38:03 INFO - ##### Running read-buildbot-config step. 08:38:03 INFO - ##### 08:38:03 INFO - Running pre-action listener: _resource_record_pre_action 08:38:03 INFO - Running main action method: read_buildbot_config 08:38:03 INFO - Using buildbot properties: 08:38:03 INFO - { 08:38:03 INFO - "project": "", 08:38:03 INFO - "product": "firefox", 08:38:03 INFO - "script_repo_revision": "production", 08:38:03 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:38:03 INFO - "repository": "", 08:38:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:38:03 INFO - "buildid": "20160704060523", 08:38:03 INFO - "pgo_build": "True", 08:38:03 INFO - "basedir": "/builds/slave/test", 08:38:03 INFO - "buildnumber": 344, 08:38:03 INFO - "slavename": "talos-linux64-ix-007", 08:38:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:38:03 INFO - "platform": "ubuntu64_hw", 08:38:03 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:38:03 INFO - "branch": "mozilla-aurora", 08:38:03 INFO - "repo_path": "releases/mozilla-aurora", 08:38:03 INFO - "revision": "3a78197e37b6ffaa41aa426f4a7d8479c061febc", 08:38:03 INFO - "stage_platform": "linux64-pgo", 08:38:03 INFO - "builduid": "a650a638d545412a94d9f8e7a17ca06e", 08:38:03 INFO - "slavebuilddir": "test" 08:38:03 INFO - } 08:38:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:38:03 INFO - Running post-action listener: _resource_record_post_action 08:38:03 INFO - ##### 08:38:03 INFO - ##### Finished read-buildbot-config step (success) 08:38:03 INFO - ##### 08:38:03 INFO - ##### 08:38:03 INFO - ##### Running download-and-extract step. 08:38:03 INFO - ##### 08:38:03 INFO - Running pre-action listener: _resource_record_pre_action 08:38:03 INFO - Running main action method: download_and_extract 08:38:03 INFO - mkdir: /builds/slave/test/build/tests 08:38:03 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')]} 08:38:03 INFO - https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:38:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:38:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:38:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:38:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:38:08 INFO - Downloaded 1361 bytes. 08:38:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:38:08 INFO - Using the following test package requirements: 08:38:08 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:38:08 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:38:08 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:38:08 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'jsshell-linux-x86_64.zip'], 08:38:08 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:38:08 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:38:08 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:38:08 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:38:08 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:38:08 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:38:08 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:38: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')]} 08:38:08 INFO - https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:38:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:38:09 INFO - Downloaded 24526241 bytes. 08:38:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:38:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:38:11 INFO - Return code: 0 08:38:11 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:38: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')]} 08:38:11 INFO - https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:38:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:38:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:38:16 INFO - Downloaded 24526241 bytes. 08:38:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:38:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:38:18 INFO - Return code: 0 08:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:18 INFO - https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:38:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:38:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:38:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:38:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:38:19 INFO - Downloaded 10833369 bytes. 08:38:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:38:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:38:19 INFO - Return code: 0 08:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:19 INFO - https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:38:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:38:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:38:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:38:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:38:22 INFO - Downloaded 63621552 bytes. 08:38:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:38:22 INFO - mkdir: /builds/slave/test/properties 08:38:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:38:22 INFO - Writing to file /builds/slave/test/properties/build_url 08:38:22 INFO - Contents: 08:38:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:38:22 INFO - Running post-action listener: _resource_record_post_action 08:38:22 INFO - Running post-action listener: set_extra_try_arguments 08:38:22 INFO - ##### 08:38:22 INFO - ##### Finished download-and-extract step (success) 08:38:22 INFO - ##### 08:38:22 INFO - ##### 08:38:22 INFO - ##### Running populate-webroot step. 08:38:22 INFO - ##### 08:38:22 INFO - Running pre-action listener: _resource_record_pre_action 08:38:22 INFO - Running main action method: populate_webroot 08:38:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:38:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:38:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:38:23 INFO - '--tppagecycles', 08:38:23 INFO - '7'], 08:38:23 INFO - 'tests': ['tsvgm']}}, 08:38:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:38:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:38:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:38:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:38:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['damp', 'tps']}, 08:38:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'tests': ['damp', 'tps']}, 08:38:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['dromaeo_dom']}, 08:38:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:38:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['a11yr', 08:38:23 INFO - 'ts_paint', 08:38:23 INFO - 'tpaint', 08:38:23 INFO - 'sessionrestore', 08:38:23 INFO - 'sessionrestore_no_auto_restore', 08:38:23 INFO - 'tabpaint']}, 08:38:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['tsvgx', 08:38:23 INFO - 'tsvgr_opacity', 08:38:23 INFO - 'tart', 08:38:23 INFO - 'tscrollx', 08:38:23 INFO - 'cart']}, 08:38:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:38:23 INFO - 'tsvgr_opacity', 08:38:23 INFO - 'tart', 08:38:23 INFO - 'tscrollx', 08:38:23 INFO - 'cart']}, 08:38:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'talos_options': ['--disable-e10s'], 08:38:23 INFO - 'tests': ['tp5o']}, 08:38:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'tests': ['tp5o']}, 08:38:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'talos_options': ['--disable-e10s', 08:38:23 INFO - '--xperf_path', 08:38:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:23 INFO - 'tests': ['tp5n']}, 08:38:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:23 INFO - 'talos_options': ['--xperf_path', 08:38:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:23 INFO - 'tests': ['tp5n']}}, 08:38:23 INFO - 'talos.zip': {'path': '', 08:38:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:23 INFO - Running post-action listener: _resource_record_post_action 08:38:23 INFO - ##### 08:38:23 INFO - ##### Finished populate-webroot step (success) 08:38:23 INFO - ##### 08:38:23 INFO - ##### 08:38:23 INFO - ##### Running create-virtualenv step. 08:38:23 INFO - ##### 08:38:23 INFO - Running pre-action listener: _resource_record_pre_action 08:38:23 INFO - Running main action method: create_virtualenv 08:38:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:38:23 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 08:38:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:38:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:38:23 INFO - Using real prefix '/usr' 08:38:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:38:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:38:25 INFO - Installing pip.................done. 08:38:25 INFO - Return code: 0 08:38:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:38:25 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')]} 08:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:25 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')]} 08:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:25 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:25 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 08:38:25 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 08:38:25 INFO - Using env: {'DISPLAY': ':0', 08:38:25 INFO - 'HOME': '/home/cltbld', 08:38:25 INFO - 'LANG': 'en_US.UTF-8', 08:38:25 INFO - 'LANGUAGE': 'en_US:en', 08:38:25 INFO - 'LOGNAME': 'cltbld', 08:38:25 INFO - 'MAIL': '/var/mail/cltbld', 08:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:25 INFO - 'MOZ_NO_REMOTE': '1', 08:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:25 INFO - 'NO_EM_RESTART': '1', 08:38:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:25 INFO - 'PWD': '/builds/slave/test', 08:38:25 INFO - 'SHELL': '/bin/bash', 08:38:25 INFO - 'SHLVL': '1', 08:38:25 INFO - 'TERM': 'linux', 08:38:25 INFO - 'TMOUT': '86400', 08:38:25 INFO - 'USER': 'cltbld', 08:38:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:25 INFO - '_': '/tools/buildbot/bin/python'} 08:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:25 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 08:38:25 INFO - Cleaning up... 08:38:25 INFO - Return code: 0 08:38:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:38:25 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')]} 08:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:25 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')]} 08:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:38:25 INFO - Using env: {'DISPLAY': ':0', 08:38:25 INFO - 'HOME': '/home/cltbld', 08:38:25 INFO - 'LANG': 'en_US.UTF-8', 08:38:25 INFO - 'LANGUAGE': 'en_US:en', 08:38:25 INFO - 'LOGNAME': 'cltbld', 08:38:25 INFO - 'MAIL': '/var/mail/cltbld', 08:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:25 INFO - 'MOZ_NO_REMOTE': '1', 08:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:25 INFO - 'NO_EM_RESTART': '1', 08:38:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:25 INFO - 'PWD': '/builds/slave/test', 08:38:25 INFO - 'SHELL': '/bin/bash', 08:38:25 INFO - 'SHLVL': '1', 08:38:25 INFO - 'TERM': 'linux', 08:38:25 INFO - 'TMOUT': '86400', 08:38:25 INFO - 'USER': 'cltbld', 08:38:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:25 INFO - '_': '/tools/buildbot/bin/python'} 08:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:26 INFO - Downloading/unpacking psutil>=3.1.1 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:38:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:28 INFO - Installing collected packages: psutil 08:38:28 INFO - Running setup.py install for psutil 08:38:28 INFO - building 'psutil._psutil_linux' extension 08:38:28 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 08:38:28 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 08:38:28 INFO - building 'psutil._psutil_posix' extension 08:38:28 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 08:38:28 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 08:38:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:28 INFO - Successfully installed psutil 08:38:28 INFO - Cleaning up... 08:38:28 INFO - Return code: 0 08:38:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:38:28 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')]} 08:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:28 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')]} 08:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:38:28 INFO - Using env: {'DISPLAY': ':0', 08:38:28 INFO - 'HOME': '/home/cltbld', 08:38:28 INFO - 'LANG': 'en_US.UTF-8', 08:38:28 INFO - 'LANGUAGE': 'en_US:en', 08:38:28 INFO - 'LOGNAME': 'cltbld', 08:38:28 INFO - 'MAIL': '/var/mail/cltbld', 08:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:28 INFO - 'MOZ_NO_REMOTE': '1', 08:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:28 INFO - 'NO_EM_RESTART': '1', 08:38:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:28 INFO - 'PWD': '/builds/slave/test', 08:38:28 INFO - 'SHELL': '/bin/bash', 08:38:28 INFO - 'SHLVL': '1', 08:38:28 INFO - 'TERM': 'linux', 08:38:28 INFO - 'TMOUT': '86400', 08:38:28 INFO - 'USER': 'cltbld', 08:38:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:28 INFO - '_': '/tools/buildbot/bin/python'} 08:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:38:30 INFO - Installing collected packages: mozsystemmonitor 08:38:30 INFO - Running setup.py install for mozsystemmonitor 08:38:30 INFO - Successfully installed mozsystemmonitor 08:38:30 INFO - Cleaning up... 08:38:30 INFO - Return code: 0 08:38:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:38: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')]} 08:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:30 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:30 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==2.5.1'] in /builds/slave/test/build 08:38:30 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==2.5.1 08:38:30 INFO - Using env: {'DISPLAY': ':0', 08:38:30 INFO - 'HOME': '/home/cltbld', 08:38:30 INFO - 'LANG': 'en_US.UTF-8', 08:38:30 INFO - 'LANGUAGE': 'en_US:en', 08:38:30 INFO - 'LOGNAME': 'cltbld', 08:38:30 INFO - 'MAIL': '/var/mail/cltbld', 08:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:30 INFO - 'MOZ_NO_REMOTE': '1', 08:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:30 INFO - 'NO_EM_RESTART': '1', 08:38:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:30 INFO - 'PWD': '/builds/slave/test', 08:38:30 INFO - 'SHELL': '/bin/bash', 08:38:30 INFO - 'SHLVL': '1', 08:38:30 INFO - 'TERM': 'linux', 08:38:30 INFO - 'TMOUT': '86400', 08:38:30 INFO - 'USER': 'cltbld', 08:38:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:30 INFO - '_': '/tools/buildbot/bin/python'} 08:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:31 INFO - Downloading/unpacking jsonschema==2.5.1 08:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:38:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:33 INFO - Downloading functools32-3.2.3-2.tar.gz 08:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:38:33 INFO - warning: no files found matching '*.txt' 08:38:33 INFO - no previously-included directories found matching 'build' 08:38:33 INFO - no previously-included directories found matching 'dist' 08:38:33 INFO - no previously-included directories found matching '.git*' 08:38:33 INFO - Installing collected packages: jsonschema, functools32 08:38:33 INFO - Running setup.py install for functools32 08:38:33 INFO - warning: no files found matching '*.txt' 08:38:33 INFO - no previously-included directories found matching 'build' 08:38:33 INFO - no previously-included directories found matching 'dist' 08:38:33 INFO - no previously-included directories found matching '.git*' 08:38:33 INFO - Successfully installed jsonschema functools32 08:38:33 INFO - Cleaning up... 08:38:33 INFO - Return code: 0 08:38:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:33 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:33 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 08:38:33 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 08:38:33 INFO - Using env: {'DISPLAY': ':0', 08:38:33 INFO - 'HOME': '/home/cltbld', 08:38:33 INFO - 'LANG': 'en_US.UTF-8', 08:38:33 INFO - 'LANGUAGE': 'en_US:en', 08:38:33 INFO - 'LOGNAME': 'cltbld', 08:38:33 INFO - 'MAIL': '/var/mail/cltbld', 08:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:33 INFO - 'MOZ_NO_REMOTE': '1', 08:38:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:33 INFO - 'NO_EM_RESTART': '1', 08:38:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:33 INFO - 'PWD': '/builds/slave/test', 08:38:33 INFO - 'SHELL': '/bin/bash', 08:38:33 INFO - 'SHLVL': '1', 08:38:33 INFO - 'TERM': 'linux', 08:38:33 INFO - 'TMOUT': '86400', 08:38:33 INFO - 'USER': 'cltbld', 08:38:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:33 INFO - '_': '/tools/buildbot/bin/python'} 08:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:33 INFO - Downloading/unpacking blobuploader==1.2.4 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38:35 INFO - Downloading blobuploader-1.2.4.tar.gz 08:38:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:38:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:38:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:35 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 08:38:36 INFO - Downloading docopt-0.6.1.tar.gz 08:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:38:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:38:36 INFO - Running setup.py install for blobuploader 08:38:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:38:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:38:36 INFO - Running setup.py install for requests 08:38:36 INFO - Running setup.py install for docopt 08:38:36 INFO - Successfully installed blobuploader requests docopt 08:38:36 INFO - Cleaning up... 08:38:36 INFO - Return code: 0 08:38:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38: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')]} 08:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:36 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:36 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 08:38:36 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 08:38:36 INFO - Using env: {'DISPLAY': ':0', 08:38:36 INFO - 'HOME': '/home/cltbld', 08:38:36 INFO - 'LANG': 'en_US.UTF-8', 08:38:36 INFO - 'LANGUAGE': 'en_US:en', 08:38:36 INFO - 'LOGNAME': 'cltbld', 08:38:36 INFO - 'MAIL': '/var/mail/cltbld', 08:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:36 INFO - 'MOZ_NO_REMOTE': '1', 08:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:36 INFO - 'NO_EM_RESTART': '1', 08:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:36 INFO - 'PWD': '/builds/slave/test', 08:38:36 INFO - 'SHELL': '/bin/bash', 08:38:36 INFO - 'SHLVL': '1', 08:38:36 INFO - 'TERM': 'linux', 08:38:36 INFO - 'TMOUT': '86400', 08:38:36 INFO - 'USER': 'cltbld', 08:38:36 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:36 INFO - '_': '/tools/buildbot/bin/python'} 08:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:37 INFO - Running setup.py (path:/tmp/pip-c7QU6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:37 INFO - Running setup.py (path:/tmp/pip-Q88Ayp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:37 INFO - Running setup.py (path:/tmp/pip-1pLF0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:37 INFO - Running setup.py (path:/tmp/pip-FjJMF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:37 INFO - Running setup.py (path:/tmp/pip-xXX4XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:37 INFO - Running setup.py (path:/tmp/pip-2_bUkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:37 INFO - Running setup.py (path:/tmp/pip-NN1q6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:37 INFO - Running setup.py (path:/tmp/pip-MSGWzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:37 INFO - Running setup.py (path:/tmp/pip-gBRPTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:37 INFO - Running setup.py (path:/tmp/pip-KEn0Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:37 INFO - Running setup.py (path:/tmp/pip-c1pCNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:37 INFO - Running setup.py (path:/tmp/pip-AZgOhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:37 INFO - Running setup.py (path:/tmp/pip-x8W4hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:37 INFO - Running setup.py (path:/tmp/pip-2MWwg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:38 INFO - Running setup.py (path:/tmp/pip-Sl3mx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:38 INFO - Running setup.py (path:/tmp/pip-ugHDiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:38 INFO - Running setup.py (path:/tmp/pip-8Y1w5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:38:38 INFO - Running setup.py install for manifestparser 08:38:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:38:38 INFO - Running setup.py install for mozcrash 08:38:38 INFO - Running setup.py install for mozdebug 08:38:38 INFO - Running setup.py install for mozdevice 08:38:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:38:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:38:38 INFO - Running setup.py install for mozfile 08:38:38 INFO - Running setup.py install for mozhttpd 08:38:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:38:38 INFO - Running setup.py install for mozinfo 08:38:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:38:38 INFO - Running setup.py install for mozInstall 08:38:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Running setup.py install for mozleak 08:38:39 INFO - Running setup.py install for mozlog 08:38:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Running setup.py install for moznetwork 08:38:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Running setup.py install for mozprocess 08:38:39 INFO - Running setup.py install for mozprofile 08:38:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Running setup.py install for mozrunner 08:38:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:38:39 INFO - Running setup.py install for mozscreenshot 08:38:39 INFO - Running setup.py install for moztest 08:38:39 INFO - Running setup.py install for mozversion 08:38:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:38:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:38:40 INFO - Cleaning up... 08:38:40 INFO - Return code: 0 08:38:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:40 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:40 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 08:38:40 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 08:38:40 INFO - Using env: {'DISPLAY': ':0', 08:38:40 INFO - 'HOME': '/home/cltbld', 08:38:40 INFO - 'LANG': 'en_US.UTF-8', 08:38:40 INFO - 'LANGUAGE': 'en_US:en', 08:38:40 INFO - 'LOGNAME': 'cltbld', 08:38:40 INFO - 'MAIL': '/var/mail/cltbld', 08:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:40 INFO - 'MOZ_NO_REMOTE': '1', 08:38:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:40 INFO - 'NO_EM_RESTART': '1', 08:38:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:40 INFO - 'PWD': '/builds/slave/test', 08:38:40 INFO - 'SHELL': '/bin/bash', 08:38:40 INFO - 'SHLVL': '1', 08:38:40 INFO - 'TERM': 'linux', 08:38:40 INFO - 'TMOUT': '86400', 08:38:40 INFO - 'USER': 'cltbld', 08:38:40 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:40 INFO - '_': '/tools/buildbot/bin/python'} 08:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:40 INFO - Running setup.py (path:/tmp/pip-MuI7ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:40 INFO - Running setup.py (path:/tmp/pip-Tm0djj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:40 INFO - Running setup.py (path:/tmp/pip-PNjK2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:40 INFO - Running setup.py (path:/tmp/pip-2I_DNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:40 INFO - Running setup.py (path:/tmp/pip-Js9Scc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:40 INFO - Running setup.py (path:/tmp/pip-3M2e9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:40 INFO - Running setup.py (path:/tmp/pip-EBikmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:40 INFO - Running setup.py (path:/tmp/pip-04mIzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:40 INFO - Running setup.py (path:/tmp/pip-vSHesr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:40 INFO - Running setup.py (path:/tmp/pip-nu7gog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:40 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)) 08:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:40 INFO - Running setup.py (path:/tmp/pip-RvAFKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:41 INFO - Running setup.py (path:/tmp/pip-37oJJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:41 INFO - Running setup.py (path:/tmp/pip-HoHMRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:41 INFO - Running setup.py (path:/tmp/pip-XMrblc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:41 INFO - Running setup.py (path:/tmp/pip-zkFvwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:41 INFO - Running setup.py (path:/tmp/pip-8tKZNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:41 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)) 08:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:41 INFO - Running setup.py (path:/tmp/pip-A35ys2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:41 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)) 08:38:41 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)) 08:38:41 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)) 08:38:41 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)) 08:38:41 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)) 08:38:41 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)) 08:38:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38:43 INFO - Downloading blessings-1.6.tar.gz 08:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:38:43 INFO - Installing collected packages: blessings 08:38:43 INFO - Running setup.py install for blessings 08:38:43 INFO - Successfully installed blessings 08:38:43 INFO - Cleaning up... 08:38:43 INFO - Return code: 0 08:38:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:38:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:43 INFO - Reading from file tmpfile_stdout 08:38:43 INFO - Current package versions: 08:38:43 INFO - argparse == 1.2.1 08:38:43 INFO - blessings == 1.6 08:38:43 INFO - blobuploader == 1.2.4 08:38:43 INFO - docopt == 0.6.1 08:38:43 INFO - functools32 == 3.2.3-2 08:38:43 INFO - jsonschema == 2.5.1 08:38:43 INFO - manifestparser == 1.1 08:38:43 INFO - mozInstall == 1.12 08:38:43 INFO - mozcrash == 0.17 08:38:43 INFO - mozdebug == 0.1 08:38:43 INFO - mozdevice == 0.48 08:38:43 INFO - mozfile == 1.2 08:38:43 INFO - mozhttpd == 0.7 08:38:43 INFO - mozinfo == 0.9 08:38:43 INFO - mozleak == 0.1 08:38:43 INFO - mozlog == 3.2 08:38:43 INFO - moznetwork == 0.27 08:38:43 INFO - mozprocess == 0.22 08:38:43 INFO - mozprofile == 0.28 08:38:43 INFO - mozrunner == 6.11 08:38:43 INFO - mozscreenshot == 0.1 08:38:43 INFO - mozsystemmonitor == 0.3 08:38:43 INFO - moztest == 0.7 08:38:43 INFO - mozversion == 1.4 08:38:43 INFO - psutil == 3.1.1 08:38:43 INFO - requests == 1.2.3 08:38:43 INFO - wsgiref == 0.1.2 08:38:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:43 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')]} 08:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:43 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')]} 08:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:43 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:43 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 08:38:43 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 08:38:43 INFO - Using env: {'DISPLAY': ':0', 08:38:43 INFO - 'HOME': '/home/cltbld', 08:38:43 INFO - 'LANG': 'en_US.UTF-8', 08:38:43 INFO - 'LANGUAGE': 'en_US:en', 08:38:43 INFO - 'LOGNAME': 'cltbld', 08:38:43 INFO - 'MAIL': '/var/mail/cltbld', 08:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:43 INFO - 'MOZ_NO_REMOTE': '1', 08:38:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:43 INFO - 'NO_EM_RESTART': '1', 08:38:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:43 INFO - 'PWD': '/builds/slave/test', 08:38:43 INFO - 'SHELL': '/bin/bash', 08:38:43 INFO - 'SHLVL': '1', 08:38:43 INFO - 'TERM': 'linux', 08:38:43 INFO - 'TMOUT': '86400', 08:38:43 INFO - 'USER': 'cltbld', 08:38:43 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:43 INFO - '_': '/tools/buildbot/bin/python'} 08:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 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)) 08:38:44 INFO - Cleaning up... 08:38:44 INFO - Return code: 0 08:38:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:38:44 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')]} 08:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:44 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')]} 08:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:44 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:44 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 08:38:44 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 08:38:44 INFO - Using env: {'DISPLAY': ':0', 08:38:44 INFO - 'HOME': '/home/cltbld', 08:38:44 INFO - 'LANG': 'en_US.UTF-8', 08:38:44 INFO - 'LANGUAGE': 'en_US:en', 08:38:44 INFO - 'LOGNAME': 'cltbld', 08:38:44 INFO - 'MAIL': '/var/mail/cltbld', 08:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:44 INFO - 'MOZ_NO_REMOTE': '1', 08:38:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:44 INFO - 'NO_EM_RESTART': '1', 08:38:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:44 INFO - 'PWD': '/builds/slave/test', 08:38:44 INFO - 'SHELL': '/bin/bash', 08:38:44 INFO - 'SHLVL': '1', 08:38:44 INFO - 'TERM': 'linux', 08:38:44 INFO - 'TMOUT': '86400', 08:38:44 INFO - 'USER': 'cltbld', 08:38:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:44 INFO - '_': '/tools/buildbot/bin/python'} 08:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:38:44 INFO - Cleaning up... 08:38:44 INFO - Return code: 0 08:38:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:38:44 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')]} 08:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:44 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')]} 08:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:44 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 0x7f77a0466f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, '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': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', '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 08:38:44 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 08:38:44 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 08:38:44 INFO - Using env: {'DISPLAY': ':0', 08:38:44 INFO - 'HOME': '/home/cltbld', 08:38:44 INFO - 'LANG': 'en_US.UTF-8', 08:38:44 INFO - 'LANGUAGE': 'en_US:en', 08:38:44 INFO - 'LOGNAME': 'cltbld', 08:38:44 INFO - 'MAIL': '/var/mail/cltbld', 08:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:44 INFO - 'MOZ_NO_REMOTE': '1', 08:38:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:44 INFO - 'NO_EM_RESTART': '1', 08:38:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:44 INFO - 'PWD': '/builds/slave/test', 08:38:44 INFO - 'SHELL': '/bin/bash', 08:38:44 INFO - 'SHLVL': '1', 08:38:44 INFO - 'TERM': 'linux', 08:38:44 INFO - 'TMOUT': '86400', 08:38:44 INFO - 'USER': 'cltbld', 08:38:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:44 INFO - '_': '/tools/buildbot/bin/python'} 08:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:44 INFO - Downloading/unpacking flake8 08:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:38:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:38:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 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 08:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:38:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:47 INFO - no previously-included directories found matching 'docs/_build' 08:38:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:38:47 INFO - Running setup.py install for pep8 08:38:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:47 INFO - no previously-included directories found matching 'docs/_build' 08:38:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:38:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:38:47 INFO - Cleaning up... 08:38:47 INFO - Return code: 0 08:38:47 INFO - Running post-action listener: _resource_record_post_action 08:38:47 INFO - Running post-action listener: _start_resource_monitoring 08:38:47 INFO - Starting resource monitoring. 08:38:47 INFO - ##### 08:38:47 INFO - ##### Finished create-virtualenv step (success) 08:38:47 INFO - ##### 08:38:47 INFO - ##### 08:38:47 INFO - ##### Running install step. 08:38:47 INFO - ##### 08:38:47 INFO - Running pre-action listener: _resource_record_pre_action 08:38:47 INFO - Running main action method: install 08:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:47 INFO - Reading from file tmpfile_stdout 08:38:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:38:47 INFO - Reading from file tmpfile_stdout 08:38:47 INFO - Output received: 08:38:47 INFO - Usage: mozinstall [options] installer 08:38:47 INFO - Options: 08:38:47 INFO - -h, --help show this help message and exit 08:38:47 INFO - -d DEST, --destination=DEST 08:38:47 INFO - Directory to install application into. [default: 08:38:47 INFO - "/builds/slave/test"] 08:38:47 INFO - --app=APP Application being installed. [default: firefox] 08:38:47 INFO - mkdir: /builds/slave/test/build/application 08:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:39:03 INFO - Reading from file tmpfile_stdout 08:39:03 INFO - Output received: 08:39:03 INFO - /builds/slave/test/build/application/firefox/firefox 08:39:03 INFO - Running post-action listener: _resource_record_post_action 08:39:03 INFO - ##### 08:39:03 INFO - ##### Finished install step (success) 08:39:03 INFO - ##### 08:39:03 INFO - ##### 08:39:03 INFO - ##### Running run-tests step. 08:39:03 INFO - ##### 08:39:03 INFO - Running pre-action listener: _resource_record_pre_action 08:39:03 INFO - Running main action method: run_tests 08:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:39:03 INFO - Python 2.7.3 08:39:03 INFO - Return code: 0 08:39:03 INFO - grabbing minidump binary from tooltool 08:39:03 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')]} 08:39:03 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 0x226e1c0>, '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 0x226f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:39:03 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 08:39:03 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 08:39:03 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 08:39:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:39:03 INFO - Return code: 0 08:39:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:39:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:39:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:39:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:39:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:39:04 INFO - Return code: 0 08:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 08:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 08:39:04 INFO - Using env: {'DISPLAY': ':0', 08:39:04 INFO - 'HOME': '/home/cltbld', 08:39:04 INFO - 'LANG': 'en_US.UTF-8', 08:39:04 INFO - 'LANGUAGE': 'en_US:en', 08:39:04 INFO - 'LOGNAME': 'cltbld', 08:39:04 INFO - 'MAIL': '/var/mail/cltbld', 08:39:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:39:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:04 INFO - 'MOZ_NO_REMOTE': '1', 08:39:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:04 INFO - 'NO_EM_RESTART': '1', 08:39:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:04 INFO - 'PWD': '/builds/slave/test', 08:39:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:39:04 INFO - 'SHELL': '/bin/bash', 08:39:04 INFO - 'SHLVL': '1', 08:39:04 INFO - 'TERM': 'linux', 08:39:04 INFO - 'TMOUT': '86400', 08:39:04 INFO - 'USER': 'cltbld', 08:39:04 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873', 08:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:04 INFO - '_': '/tools/buildbot/bin/python'} 08:39:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 08:39:04 INFO - mozversion application_buildid: 20160704060523 08:39:04 INFO - mozversion application_changeset: 3a78197e37b6ffaa41aa426f4a7d8479c061febc 08:39:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:39:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:39:04 INFO - mozversion application_name: Firefox 08:39:04 INFO - mozversion application_remotingname: firefox-dev 08:39:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:39:04 INFO - mozversion application_vendor: Mozilla 08:39:04 INFO - mozversion application_version: 49.0a2 08:39:04 INFO - mozversion platform_buildid: 20160704060523 08:39:04 INFO - mozversion platform_changeset: 3a78197e37b6ffaa41aa426f4a7d8479c061febc 08:39:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:39:04 INFO - mozversion platform_version: 49.0a2 08:39:04 INFO - using testdate: 1467646744 08:39:04 INFO - actual date: 1467646744 08:39:04 INFO - starting webserver on 'localhost:43449' 08:39:04 INFO - SUITE-START | Running 2 tests 08:39:04 INFO - TEST-START | dromaeo_css 08:39:04 INFO - operating with platform_type : linux_ 08:39:04 INFO - Initialising browser for dromaeo_css test... 08:39:04 INFO - TEST-INFO | started process 15652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHYrNNf/profile http://localhost:43449/getInfo.html) 08:39:08 INFO - PROCESS | 15652 | __metrics Screen width/height:1600/1200 08:39:08 INFO - PROCESS | 15652 | colorDepth:24 08:39:08 INFO - PROCESS | 15652 | Browser inner width/height: 1024/697 08:39:08 INFO - PROCESS | 15652 | __metrics 08:39:09 ERROR - PROCESS | 15652 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:39:10 INFO - TEST-INFO | 15652: exit 0 08:39:10 INFO - Browser initialized. 08:39:10 INFO - Running cycle 1/1 for dromaeo_css test... 08:39:10 INFO - TEST-INFO | started process 15744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHYrNNf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:39:22 INFO - PROCESS | 15744 | RSS: Main: 145461248 08:39:22 INFO - PROCESS | 15744 | 08:40:13 INFO - PROCESS | 15744 | 1467646813238 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] 08:42:21 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43449/tests/dromaeo/cssquery-ext.html) 08:42:22 INFO - PROCESS | 15744 | RSS: Main: 254619648 08:42:22 INFO - PROCESS | 15744 | 08:45:24 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-ext.html (next: http://localhost:43449/tests/dromaeo/cssquery-jquery.html) 08:45:25 INFO - PROCESS | 15744 | RSS: Main: 228630528 08:45:25 INFO - PROCESS | 15744 | 08:48:27 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43449/tests/dromaeo/cssquery-mootools.html) 08:48:27 INFO - PROCESS | 15744 | RSS: Main: 231129088 08:48:27 INFO - PROCESS | 15744 | 08:51:29 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43449/tests/dromaeo/cssquery-prototype.html) 08:51:30 INFO - PROCESS | 15744 | RSS: Main: 226623488 08:51:30 INFO - PROCESS | 15744 | 08:54:32 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43449/tests/dromaeo/cssquery-yui.html) 08:54:33 INFO - PROCESS | 15744 | RSS: Main: 218161152 08:54:33 INFO - PROCESS | 15744 | 08:57:35 INFO - PROCESS | 15744 | Cycle 1(1): loaded http://localhost:43449/tests/dromaeo/cssquery-yui.html (next: http://localhost:43449/tests/dromaeo/cssquery-dojo.html) 08:57:36 INFO - PROCESS | 15744 | RSS: Main: 256622592 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | __start_tp_report 08:57:36 INFO - PROCESS | 15744 | _x_x_mozilla_page_load 08:57:36 INFO - PROCESS | 15744 | _x_x_mozilla_page_load_details 08:57:36 INFO - PROCESS | 15744 | |i|pagename|runs| 08:57:36 INFO - PROCESS | 15744 | |0;dojo.html;3310.9503357449644;3315.220923083075;3335.1495368680985;3347.7489188310874;3355.5973283206017;5507.531859791918;5596.524295434882;5597;5616.073347897597;5622.381538030819;5471.37079235888;5534.944650553493;5571.609987300891;5580.888382232363;5602.047651899176;4921.261810728383;4987.202047672373;5010.899782004376;5037.093323201822;5076.923846142311;4898.167311557026;5035.823746168885;5066.138756411419;5077.238414237875;5077.822276220333;4991.800327986876;5006.4492905780335;5019.673721208128;5037.546620804127;5086.160783470723;5102.515261050195;5115.66748161372;5130.53825155734;5135.691858488465;5161.870953226181;4842.080004799077;4922.532359425849;4959.553640172366;5038.546530812245;5042.369703787027;5339.385970613391;5347.41178470368;5355.437679043722;5365.731713414352;5384.030874442598;5824.446677565625;5876.97061514694;5904.5571582131515;5904.61619994699;5910.645361278337;5797.739101740432;5898.498627616657;5940;5947.910781338283;6015.187949626832;5582.888342233173;5615.9157612635845;5623.353314368859;5626.493615574577;5630.746616402272;5949.077892926603;6085.174447665682;6091.969540152271;6093.173869005767;6131.14164017038;5765.164051212634;5792.391798861143;5792.971035144881;5896.174535565025;5896.3219229788265;5946.286445626552;5951.970240148771;5952.345242023374;5975.850603734942;5986.5809393342915;5631.211630371751;5841.474267315962;5904.527637788967;5912.201852749909;5919.437653422918;5890.410958904161;5895.734691938787;5899.911501327483;5912.142739302777;5934.732937017759;4524.23088075028;4525.502194758574;4528.162289976365;4534.093181363741;4554.453465584151;3961.7622942623534;3989.6608788252743;4014.3577027676015;4037.414574886625;4072.9796351018053;5647.971760141255;5668.149777533321;5672.177534257592;5672.262605861211;5693.430656934356;5960.552958528127;5962.85092872673;5969.52243820502;5973.581849270597;6008.939910600838;5484.095124304525;5556.583256255797;5603.215549823028;5603.915941260884;5607.775688972477;5064.1137800885435;5092.694438333712;5092.719900405513;5097.974510127426;5098.490150984871;6082.330943596201;6088.6042407243385;6091.360407157274;6093.360197179321;6094.085887116968;5815.5929084963645;5880.294364676266;5936.8812623748145;5941.049432090845;5999.460048595647;6180.196574445294;6185;6188.597741146811;6194.628322300677;6199.28708198563;5648.15277708347;5859.974504461617;5875.529957603296;5886.38192989747;5894.351621321565;5861.32594751609;5880.647161170344;5909.674967876808;5975.014122669711;5975.9103613446705;5813.82558523245;5848.824535263949;5858.794942177004;5858.8242352729485;5859.326177489644;5591.692456914909;5692.032354499662;5764.337101233412;5796.188533605215;5817.214676018683;5612.158176273591;5689.573282003784;5690.3456102548735;5695.715214239354;5727.656340619576;5772.249607550992;5779.844031193862;5800.796972105956;5803.013487707016;5822.534197264294;5521.558275338044;5635.690037048002;5674.829755107354;5695.7436915338085;5711.114777209539 08:57:36 INFO - PROCESS | 15744 | |1;ext.html;584.1704779213517;654.9901751473728;663.7013343995201;664.6809531424913;670.6780745242287;32760.180995475097;38566.8071659642;38775.612243877586;39191;39363.21273574527;48323;48710.51289487104;48832.75583622086;48858.755706221506;48908.02183956319;8454.408191426603;9092.136247056536;9257.16685498305;9265.073492650714;10861.456927153651;10617.840732388982;10999.835002474969;11006;11015.889841101607;11037.779244415147;11143.275687080355;11200.775984480306;11202.943985280062;11211.719707007309;11214.66356009321;8556.657733690676;10932.344059356465;11025.61410350638;11222;11233.887661123448;10626.787464250749;11029.44852757359;11059.336439813636;11066.612668556643;11073.723156921142;47399.76300118512;47761.76119119417;47920.520794791955;47991;47996.760016200045;3968.2460332536725;3986.66113380363;4007.1184339445276;4499.392582001421;4620.376249206348;35561.82219088888;36298;36318.27363452716;36363.272734545164;37975.620243797486;3947.1710940702487;4008.078142027321;4014.6186112319283;4022.6781857451333;4028.47629808126;312097;386558;387331;387486;388365;39164.80417597894;40190.799046004584;72518.27481725115;73299.26700732924;73473;41908.161836763094;41986.37020444696;42139.15721685549;42156.789216054334;42165.57834421678;18117;18380.908095459436;18383.080845957647;18385.356512522267;18393.90803046003;13970.860291396955;14120.082194657314;14146.151230925972;14151.504697335544;14488.492902748563;9274.721758347261;9285.25717942563;9288.489133097744;9306.162445379949;9371.765705857408;3575;3618.3306088373733;3678.7976661283888;3692.2800053989213;3698.130939229279;1075.714935542082;1105.812011957953;1125.5441546173813;1126.6086383316597;1193.76721539299;34984.825075874454;35044.47433288502;35064.82467587646;35073.29853402969;35287.4706879397;1151.8230544271335;1209.9879001209874;1227.5948936850818;1245.7633049720523;1253.5173958026044;12754.553590624284;16266.349346026027;17338.826611733723;18021.549461263574;18456.630867382846;625349;631255;632803;634084;638587;64926;65455;65505;65622.34377656163;65657.34342656512;1389.124851343647;1450.4778279819257;1452.5351887396143;1470.7058588282173;1508.7812267221411;34004;34095.65904341024;34231.31537369189;34771.30457390787;34930.47604285989;53003.20495192498;53455.73272133614;53557.196642049625;53587.73206133944;53825.19262210991;341598;348426;348686;348979;349011;26585.670716464487;29078.273043173245;29800.850995744884;30528.389432210788;30841.8457907709;48031.75984120057;48733;48980;49486.257706133714;49844.25233621571;39887.40168897411;40753.1849363017;41062.1787564253;41406.79296603498;42221.788891056574;40720.59279407168;40759.38860917148;40939.7953010233;41029;42046.36930445985;36882.8155859219;43687.78156109199;45047.549524504335;45132.32301515545;47194.29208561943;40180.598194017686;40457.797711011255;40884.79557602193;41325.1734965293;41964.58035419607;29733;30381;30530.694693053672;30712.539311910787;30855.382892342477 08:57:36 INFO - PROCESS | 15744 | |2;jquery.html;420.49330556679206;429.31738535728186;429.67988848308005;431.8704388683396;438.79595987865656;19149.329773457932;20322.1871125155;20940.685889711647;21822.672659910113;21824.454388640293;19941.40175794725;20294.594108117828;20455.59088818223;20467.590648187066;20614.072366743494;5576;5815.970920145393;5844.678542680151;5851.093080572518;5860.794872179475;5603.859903502404;5646.068398714207;5762.1068734346245;5891.469767720904;5895.911561326562;5918.171455996164;5928.6739229342365;5929.8814023719715;5942.049272116462;5943.465088142067;5830.3586605473365;5837.941620583803;5925.822225333247;5951.375105613892;5967.16459695643;5633.380328163898;5699.629524080921;5725.828225153252;5729.684867332295;5778.219940308047;13748.793768093487;14059.929700351535;14059.929700351535;14065.859341406558;15187.696246075016;2360.079568968104;2380.143148466551;2467.716212635534;2630.1320564213956;2690.166048524944;10039.548220330104;10204.183665306758;10489.737756556147;13271.535496257584;13551.728965420734;1987.4633848860726;2001.449601359623;2010.3566858605252;2096.3920463065692;2154.450615093141;62738.686306568175;69928;84897.15102848955;85125;89150.55424722836;10990.120790336754;11455.59905403307;11568.24806387582;11634.65096047103;11881.524739010516;16448.671026579403;16537.586560335847;16616.83383166177;16633.66732665364;16652.75020874688;7547.584882831387;9049.411788233743;9077.863832042525;9088.227500662519;9245.768855778524;8368.748937531884;8599.484030958038;9223.49270790113;9334.206592439583;9346.813063738688;4863.829765958245;4956.430010548761;5013.523715247045;5015;5028.974855125701;1950.4538729155859;1958;1995.6507611167988;2007.217185297735;2032.9695054574192;972.4748635736764;1036.388530766851;1085.945702714877;1101.146611376174;1121.4785124916953;19842.70235946462;19871.701924471145;20000.299989500298;20062.498437538885;20122.496937576387;1041.2971244409994;1048.6801525534634;1080.3625860742043;1085.6634443322596;1104.7403860092872;10822.729431764268;11136.832947505794;11251.324920504632;11293.604723834625;11303.434828258554;479360;485886;490169;491384;504082;80945.59527202326;95756;99387.50306248422;101349.49325253474;103308;1070.4273213830563;1101.9504122314354;1108.1024370260116;1108.51225460797;1114.966551003455;17583.560410989317;17924.91037544804;18003.695011734053;18055.097245137953;18461;43239.35140972951;43918.56081439145;44388.77805610951;44437;45316;56940.43059569351;58247.41752582589;60662;61718.382816173056;62430.37569624246;8263.545504997282;8315.792105197177;8402.495850249004;8446.788830279293;8514.574271286287;9255.53722313895;12110.878891210976;12394.380280986095;12585.748285034431;12654.114212005072;10719.517621707198;10757.677269681923;10894.727631809268;11019.669409917715;11165.49755260999;10730.946345268223;10733.409662468639;10748.731281718019;10942;11002.174836887425;10784.460776960963;10791.568337266735;10871.891281087088;10945.945270273598;11405.372704501;8286.502809831429;8321.875171872547;8392.328613710766;8419.70531031411;8525.74422767318;7953.32396746283;9353.345265831598;10607.469626518488;10939.124870010539;11060.944695276472 08:57:36 INFO - PROCESS | 15744 | |3;mootools.html;1419.8438171801095;1563.1480842940593;1587.809462864456;1774.73378993151;1946.6009468059058;8675.43609665372;9045.728628141158;9064.138906803852;9341.39280946738;9363.90636093639;9093.454392736441;9291.953540232289;9293.814123717522;9297.163255307021;9299.02360252174;2084.687296905469;2317.7450480447137;2331.3122628824453;2359.4573248152933;2399.78401943825;2443.6701045358914;2451.7548245175517;2464.987675061622;2471.7404672509397;2477.5168842075805;2368.0409434179187;2396.6405039244164;2398.796102331304;2399.436132508859;2400.567897778399;2013.4664313956814;2302.24026071396;2374.4301367671806;2376.4534157143953;2393.198278576679;2377.405648587853;2379.9524009519887;2381.535600557892;2382.285314405671;2414.2757172848073;9568.712938611852;9610.327277090606;9627.277954153467;9627.711168664951;9636.662716804949;5171.715555644438;5371.435999220064;5373.59698022646;5374.6237763356585;5378.220158077057;3211.486162214058;3297.950530742041;3356.848941797625;3398.235397035672;3404.914877128067;1078.0567003871613;1086.630545614487;1105.336797921249;1106.0819519798547;1118.1893127482592;13641.86358136416;13737.725245495134;13740.725185496234;13740.862591374058;13753.38109785062;4619.607333376701;4634.119517291704;4708.858734237979;4719.764011799465;4740.051989602093;11306.378149201708;11324.830127548094;11337.43312834355;11369.31784092957;11371.715707107389;4836.733979631154;5535.916961245504;5543.36251331094;5544.528715059265;5546.334439867242;4722.763861806896;4859.198232291632;4861.392325959255;4879.121758083508;4885.5358740919555;4011.6389524942897;4023.796861875819;4025.0742329264435;4035.1929614077303;4038.9798051009557;1231.2181764579377;1259.030546479206;1261.2621616354352;1269.2130878854985;1270.4791035675328;929.6049179098856;991.8859331176862;1030.314840630987;1062.654637242899;1068.8770791358938;6341.841453963595;6417.679116044179;6421.8073457796345;6503.089567460559;6512.934870651233;420.7223232666487;421.3532228029959;422.1851825975835;429.20811103513967;435.0993443571776;4289.549597292302;4456.17560751262;4511.616512596401;4552.7495987720995;4558.202314594932;11507.88492115085;11930.940345298392;12009.339486328161;12027.27836329823;12046.698832529082;11689.123315751352;11724.941375293069;11777.116716246315;11781.234219775688;11789.056875449944;8371.414001019992;8688.348373871984;8697.869531957025;8783;8893.99999999987;6555.475561955128;6654.534182607171;6726.630035348103;6771.492138089761;6787.728490860508;10428.791424171322;10715.142788576739;10774.24580279373;10834.566617335533;10929.83605245906;10812;10927.56289748433;10940.781184376108;10952.288101273392;10988.780224395307;7380.5940673263485;7585.810354740955;7908.5650289234645;8027.317677997436;8140.185981402049;9023.548822558714;9373.20327772147;9395.436273823592;9407.341486095907;9421.905780942378;8166.142555031636;8329.708460203863;8366.205210504992;8423.663053477792;8512.361572881935;7489.737859174903;7683.731069412544;7707;7738.7678369649;7765.883511747215;7359;7371.520851144658;7420.220876807857;7421.443391745533;7545.584992825447;5263.210518422115;5299.534567827244;5537.889242215053;5548.112302031655;5563.693996830213;5616.578756593353;5619.072852979212;5624.3250809903075;5701.315842098975;5720.342160651412 08:57:36 INFO - PROCESS | 15744 | |4;prototype.html;332.78859010548206;339.7231256525931;342.4469481786915;346.50276852716337;347.98086105264207;5511.3937466878615;5538.833834984946;5610.102383618621;5641.164008474241;5688.943110568893;5557.166425036246;5566.415526369724;5568.86077848053;5611.494965453108;5614.831555053344;4348.956510434887;4349.652027837781;4355.259605867002;4403.4495688039;4418.5581441855875;4236.191986520309;4349.195398851222;4387.583179597933;4429.31685959335;4443.84446544371;4265.381519679645;4324.286492728696;4325.307950727884;4354.281543545311;4355.978220108911;4294.871153865389;4294.935575966332;4296.957030429702;4300.290452075404;4307.698461107724;3696.537932758405;3697.7781333120097;3808.733388662795;3813.218290250492;3813.561440434358;5300.727825321895;5316;5319.228711836762;5329.920051199235;5333.413324534298;4873.220284754458;4886.267059941002;4899.534544218293;4910.680805747615;4911.336969509106;5482.341064583674;5777.815547812688;5814.680192589406;5832.591718579701;5834.941650583524;4140.399642051916;4148.398482220091;4166.729162604421;4168.916081818718;4176.227397931392;6046.455818976313;6097.939020609781;6101.389861013908;6105.847353816146;6124.755009799603;4178.059936514321;4249.171411574775;4261.529772228576;4291.334843099325;4297.119090586422;4284.207421627009;4288.335308027198;4295.398644189816;4296.828126874952;4303.311470164758;4886.95113048865;4889.828855990023;4892.633052521075;4904.950950490521;4932;5590.965671350813;5717.599768016282;5858.384869588717;5884.882302354014;5891.793787217427;1449.746294398476;1474.7390980711336;1480.659448326891;1481.6740317130213;1484.5249520153448;903.590398977597;908.2506931781306;924.5885580916562;931.0410277414275;943.3208090175063;5127.461906356378;5135.820246291362;5145.170156413958;5157.871053223625;5185.714785686748;5343.625946183807;5379.946200538023;5385.946140538623;5421;5433.8369848904595;5073.695578265316;5084.491550844886;5087.211482220262;5369.892602148013;5820.76716931318;3199.5520627112223;3212.5984251968503;3221;3222.5166225066428;3224.4196044711707;6129.84675383119;6200.658963756947;6245.219347581552;6260.906086408708;6294.9226755170685;6109.969450152721;6122.265328160649;6133.049377346519;6140.508759299245;6176.320604733476;6190.721417536131;6266.276773887217;6272.404121608532;6294.4020318070625;6296.212973378328;5820.388859169726;6050.5764596479585;6057.879292330933;6063.363346848518;6083.482903953037;5951.7619295227705;5978.923793717173;5978.940210597838;5982.102684597346;6046.213992181164;5994.28068631767;6273.9372606273355;6277.905831412441;6296.811095667137;6302.5588208826775;4227.070044590185;4256.446661933929;4262.275413179829;4266.786660666892;4276.401303817406;5745.971270143623;5811;6017.939820601738;6078.939210607838;6088.391160883876;5929.140274660236;5948.910766338422;5965;5971.4327138921735;5991.940080599313;5873.647581145145;5926;5930.199423077829;5943.583949123693;5948.8810223796145;5600.663960162403;5847.327557330961;5850.736716847837;5857.560682948882;5867.706614669164;5946.881062378641;5972.850678732892;5988.790392336247;5997.70011499432;6007.789727359522;5622.409646987007;5762.855928601818;5793.768249269982;5813.56398270123;5842.941570584239 08:57:36 INFO - PROCESS | 15744 | |5;yui.html;813.7884150120967;855.5551113421024;860.9741707748767;883.284539522986;904.0868722590182;15784.36862525499;17127.91436042821;17151.656966860653;17169;17180.3127874885;15671.138087405188;15942.920285398586;15966.121863297514;15972.121533315663;16005.199740012982;722.1658983873634;846.0312941681801;858.8926384201975;863.7667829685981;864.9264812490945;871.5729292646589;875.2560323724847;881.4314766975297;881.6297155194801;881.8633111867671;909.8908131024286;916.5554705967601;917.6833992272656;922.4603606889978;922.9353945223837;750.8122969257686;755.765712629084;756.606564586412;890.0387581412059;905.1310741687987;660.6366498425847;661.0579923608839;661.206552137437;661.256086902235;665.9167604049494;14395.856041439556;14407.639809004859;14446.711065778625;14446.988710790249;14463.566093017227;14656.193909334983;14977.326020329003;15248.695026099527;15268.38926442941;15270.16014119223;12472.50109995599;12509.624711258677;12665.683357916034;12759.61721148367;12764.489420423171;4603.562661547181;4658.301254811805;4667.183242932473;4686.929696054562;4785.712857228577;85021;85628;86107.56946215229;87015.56492217562;87095.56452217762;12348.567800126952;12368.938155309166;12369.752604947851;12561.56034538805;12649.683757906127;22681.2061577844;22732.886335568215;22765.544689106126;22798.632082075128;22801.543969120525;8935.553222338987;8950.328725345495;9057.09429057089;9208.171264586219;9211.63153473867;4713.104510143062;4873.244647079709;4876.804927802848;4969.3291405659775;5090.2364645303505;3564.8395822188063;3655.6161603031833;3659.0852286928266;3677.8161091945294;3679.1170119171743;626.2078470734524;651.7230177174682;659.475716805141;660.986780264392;670.8389986403228;727.9818004549928;728.5373787644884;729.5877829026583;731.4294850016995;747.1781040855125;9179.219766319937;9577.377470464537;9698.418094914328;9704.126628603319;9721;612.8100288910452;631.8009826904511;684.1926526698544;690.4234963805279;728.8578727148262;4683;4688.179568575554;4828.565429111397;4852.927206091911;4859.052484765508;69345;76936.23063769403;97664.51167744258;99669;101347;97062.51468742611;98697.50651246698;98777;99157;99617;88183;89217.10782892218;89931.55034224917;90678;91195.5440222808;9958.253131015348;10259;10277.9486102569;10392;10395.428251446243;51793.48206517887;52287.73856130847;52380.7380963108;53036.73481632567;53801.19298210452;50079.74960125176;77552;78507;78570;78570.6071469639;12898.80651790205;13088.214707117604;13374.197548147142;14059.367328470038;14343.426262949366;15229.771553426928;15562.688746224785;15713.528594142193;15990.357573322417;16946.152692365085;13172.47310107585;13726.725465490834;13966.441342346192;14430.494932677306;14764;14120.293985300488;14337.64155896111;14710.63223419423;14780.704385912437;14927.253637318308;13602.795958060835;13787.241701706502;13953.441862325393;13973.371198295898;14016.64958376008;12057.939710301393;12114.030877529953;12249.632511024683;12393.070519710878;12448.75102497963;11828;11958.64124076279;11996.880031199576;12211.206271592318;12239.571614993789 08:57:36 INFO - PROCESS | 15744 | __end_tp_report 08:57:36 INFO - PROCESS | 15744 | __start_cc_report 08:57:36 INFO - PROCESS | 15744 | _x_x_mozilla_cycle_collect,867 08:57:36 INFO - PROCESS | 15744 | __end_cc_report 08:57:36 INFO - PROCESS | 15744 | __startTimestamp1467647856143__endTimestamp 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | ------- Summary: start ------- 08:57:36 INFO - PROCESS | 15744 | Number of tests: 6 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#0] dojo.html Cycles:165 Average:5500.80 Median:5690.35 stddev:613.69 (10.8%) stddev-sans-first:591.04 08:57:36 INFO - PROCESS | 15744 | Values: 3311.0 3315.2 3335.1 3347.7 3355.6 5507.5 5596.5 5597.0 5616.1 5622.4 5471.4 5534.9 5571.6 5580.9 5602.0 4921.3 4987.2 5010.9 5037.1 5076.9 4898.2 5035.8 5066.1 5077.2 5077.8 4991.8 5006.4 5019.7 5037.5 5086.2 5102.5 5115.7 5130.5 5135.7 5161.9 4842.1 4922.5 4959.6 5038.5 5042.4 5339.4 5347.4 5355.4 5365.7 5384.0 5824.4 5877.0 5904.6 5904.6 5910.6 5797.7 5898.5 5940.0 5947.9 6015.2 5582.9 5615.9 5623.4 5626.5 5630.7 5949.1 6085.2 6092.0 6093.2 6131.1 5765.2 5792.4 5793.0 5896.2 5896.3 5946.3 5952.0 5952.3 5975.9 5986.6 5631.2 5841.5 5904.5 5912.2 5919.4 5890.4 5895.7 5899.9 5912.1 5934.7 4524.2 4525.5 4528.2 4534.1 4554.5 3961.8 3989.7 4014.4 4037.4 4073.0 5648.0 5668.1 5672.2 5672.3 5693.4 5960.6 5962.9 5969.5 5973.6 6008.9 5484.1 5556.6 5603.2 5603.9 5607.8 5064.1 5092.7 5092.7 5098.0 5098.5 6082.3 6088.6 6091.4 6093.4 6094.1 5815.6 5880.3 5936.9 5941.0 5999.5 6180.2 6185.0 6188.6 6194.6 6199.3 5648.2 5860.0 5875.5 5886.4 5894.4 5861.3 5880.6 5909.7 5975.0 5975.9 5813.8 5848.8 5858.8 5858.8 5859.3 5591.7 5692.0 5764.3 5796.2 5817.2 5612.2 5689.6 5690.3 5695.7 5727.7 5772.2 5779.8 5800.8 5803.0 5822.5 5521.6 5635.7 5674.8 5695.7 5711.1 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#1] ext.html Cycles:180 Average:61567.69 Median:387408.50 stddev:124957.80 (32.3%) stddev-sans-first:125224.45 08:57:36 INFO - PROCESS | 15744 | Values: 584.2 655.0 663.7 664.7 670.7 32760.2 38566.8 38775.6 39191.0 39363.2 48323.0 48710.5 48832.8 48858.8 48908.0 8454.4 9092.1 9257.2 9265.1 10861.5 10617.8 10999.8 11006.0 11015.9 11037.8 11143.3 11200.8 11202.9 11211.7 11214.7 8556.7 10932.3 11025.6 11222.0 11233.9 10626.8 11029.4 11059.3 11066.6 11073.7 47399.8 47761.8 47920.5 47991.0 47996.8 3968.2 3986.7 4007.1 4499.4 4620.4 35561.8 36298.0 36318.3 36363.3 37975.6 3947.2 4008.1 4014.6 4022.7 4028.5 312097.0 386558.0 387331.0 387486.0 388365.0 39164.8 40190.8 72518.3 73299.3 73473.0 41908.2 41986.4 42139.2 42156.8 42165.6 18117.0 18380.9 18383.1 18385.4 18393.9 13970.9 14120.1 14146.2 14151.5 14488.5 9274.7 9285.3 9288.5 9306.2 9371.8 3575.0 3618.3 3678.8 3692.3 3698.1 1075.7 1105.8 1125.5 1126.6 1193.8 34984.8 35044.5 35064.8 35073.3 35287.5 1151.8 1210.0 1227.6 1245.8 1253.5 12754.6 16266.3 17338.8 18021.5 18456.6 625349.0 631255.0 632803.0 634084.0 638587.0 64926.0 65455.0 65505.0 65622.3 65657.3 1389.1 1450.5 1452.5 1470.7 1508.8 34004.0 34095.7 34231.3 34771.3 34930.5 53003.2 53455.7 53557.2 53587.7 53825.2 341598.0 348426.0 348686.0 348979.0 349011.0 26585.7 29078.3 29800.9 30528.4 30841.8 48031.8 48733.0 48980.0 49486.3 49844.3 39887.4 40753.2 41062.2 41406.8 42221.8 40720.6 40759.4 40939.8 41029.0 42046.4 36882.8 43687.8 45047.5 45132.3 47194.3 40180.6 40457.8 40884.8 41325.2 41964.6 29733.0 30381.0 30530.7 30712.5 30855.4 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#2] jquery.html Cycles:180 Average:29066.97 Median:11988.56 stddev:80949.73 (675.2%) stddev-sans-first:81148.24 08:57:36 INFO - PROCESS | 15744 | Values: 420.5 429.3 429.7 431.9 438.8 19149.3 20322.2 20940.7 21822.7 21824.5 19941.4 20294.6 20455.6 20467.6 20614.1 5576.0 5816.0 5844.7 5851.1 5860.8 5603.9 5646.1 5762.1 5891.5 5895.9 5918.2 5928.7 5929.9 5942.0 5943.5 5830.4 5837.9 5925.8 5951.4 5967.2 5633.4 5699.6 5725.8 5729.7 5778.2 13748.8 14059.9 14059.9 14065.9 15187.7 2360.1 2380.1 2467.7 2630.1 2690.2 10039.5 10204.2 10489.7 13271.5 13551.7 1987.5 2001.4 2010.4 2096.4 2154.5 62738.7 69928.0 84897.2 85125.0 89150.6 10990.1 11455.6 11568.2 11634.7 11881.5 16448.7 16537.6 16616.8 16633.7 16652.8 7547.6 9049.4 9077.9 9088.2 9245.8 8368.7 8599.5 9223.5 9334.2 9346.8 4863.8 4956.4 5013.5 5015.0 5029.0 1950.5 1958.0 1995.7 2007.2 2033.0 972.5 1036.4 1085.9 1101.1 1121.5 19842.7 19871.7 20000.3 20062.5 20122.5 1041.3 1048.7 1080.4 1085.7 1104.7 10822.7 11136.8 11251.3 11293.6 11303.4 479360.0 485886.0 490169.0 491384.0 504082.0 80945.6 95756.0 99387.5 101349.5 103308.0 1070.4 1102.0 1108.1 1108.5 1115.0 17583.6 17924.9 18003.7 18055.1 18461.0 43239.4 43918.6 44388.8 44437.0 45316.0 56940.4 58247.4 60662.0 61718.4 62430.4 8263.5 8315.8 8402.5 8446.8 8514.6 9255.5 12110.9 12394.4 12585.7 12654.1 10719.5 10757.7 10894.7 11019.7 11165.5 10730.9 10733.4 10748.7 10942.0 11002.2 10784.5 10791.6 10871.9 10945.9 11405.4 8286.5 8321.9 8392.3 8419.7 8525.7 7953.3 9353.3 10607.5 10939.1 11060.9 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#3] mootools.html Cycles:180 Average:6145.92 Median:4532.18 stddev:3636.32 (80.2%) stddev-sans-first:3629.18 08:57:36 INFO - PROCESS | 15744 | Values: 1419.8 1563.1 1587.8 1774.7 1946.6 8675.4 9045.7 9064.1 9341.4 9363.9 9093.5 9292.0 9293.8 9297.2 9299.0 2084.7 2317.7 2331.3 2359.5 2399.8 2443.7 2451.8 2465.0 2471.7 2477.5 2368.0 2396.6 2398.8 2399.4 2400.6 2013.5 2302.2 2374.4 2376.5 2393.2 2377.4 2380.0 2381.5 2382.3 2414.3 9568.7 9610.3 9627.3 9627.7 9636.7 5171.7 5371.4 5373.6 5374.6 5378.2 3211.5 3298.0 3356.8 3398.2 3404.9 1078.1 1086.6 1105.3 1106.1 1118.2 13641.9 13737.7 13740.7 13740.9 13753.4 4619.6 4634.1 4708.9 4719.8 4740.1 11306.4 11324.8 11337.4 11369.3 11371.7 4836.7 5535.9 5543.4 5544.5 5546.3 4722.8 4859.2 4861.4 4879.1 4885.5 4011.6 4023.8 4025.1 4035.2 4039.0 1231.2 1259.0 1261.3 1269.2 1270.5 929.6 991.9 1030.3 1062.7 1068.9 6341.8 6417.7 6421.8 6503.1 6512.9 420.7 421.4 422.2 429.2 435.1 4289.5 4456.2 4511.6 4552.7 4558.2 11507.9 11930.9 12009.3 12027.3 12046.7 11689.1 11724.9 11777.1 11781.2 11789.1 8371.4 8688.3 8697.9 8783.0 8894.0 6555.5 6654.5 6726.6 6771.5 6787.7 10428.8 10715.1 10774.2 10834.6 10929.8 10812.0 10927.6 10940.8 10952.3 10988.8 7380.6 7585.8 7908.6 8027.3 8140.2 9023.5 9373.2 9395.4 9407.3 9421.9 8166.1 8329.7 8366.2 8423.7 8512.4 7489.7 7683.7 7707.0 7738.8 7765.9 7359.0 7371.5 7420.2 7421.4 7545.6 5263.2 5299.5 5537.9 5548.1 5563.7 5616.6 5619.1 5624.3 5701.3 5720.3 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#4] prototype.html Cycles:180 Average:4890.70 Median:5525.11 stddev:1452.55 (26.3%) stddev-sans-first:1415.77 08:57:36 INFO - PROCESS | 15744 | Values: 332.8 339.7 342.4 346.5 348.0 5511.4 5538.8 5610.1 5641.2 5688.9 5557.2 5566.4 5568.9 5611.5 5614.8 4349.0 4349.7 4355.3 4403.4 4418.6 4236.2 4349.2 4387.6 4429.3 4443.8 4265.4 4324.3 4325.3 4354.3 4356.0 4294.9 4294.9 4297.0 4300.3 4307.7 3696.5 3697.8 3808.7 3813.2 3813.6 5300.7 5316.0 5319.2 5329.9 5333.4 4873.2 4886.3 4899.5 4910.7 4911.3 5482.3 5777.8 5814.7 5832.6 5834.9 4140.4 4148.4 4166.7 4168.9 4176.2 6046.5 6097.9 6101.4 6105.8 6124.8 4178.1 4249.2 4261.5 4291.3 4297.1 4284.2 4288.3 4295.4 4296.8 4303.3 4887.0 4889.8 4892.6 4905.0 4932.0 5591.0 5717.6 5858.4 5884.9 5891.8 1449.7 1474.7 1480.7 1481.7 1484.5 903.6 908.3 924.6 931.0 943.3 5127.5 5135.8 5145.2 5157.9 5185.7 5343.6 5379.9 5385.9 5421.0 5433.8 5073.7 5084.5 5087.2 5369.9 5820.8 3199.6 3212.6 3221.0 3222.5 3224.4 6129.8 6200.7 6245.2 6260.9 6294.9 6110.0 6122.3 6133.0 6140.5 6176.3 6190.7 6266.3 6272.4 6294.4 6296.2 5820.4 6050.6 6057.9 6063.4 6083.5 5951.8 5978.9 5978.9 5982.1 6046.2 5994.3 6273.9 6277.9 6296.8 6302.6 4227.1 4256.4 4262.3 4266.8 4276.4 5746.0 5811.0 6017.9 6078.9 6088.4 5929.1 5948.9 5965.0 5971.4 5991.9 5873.6 5926.0 5930.2 5943.6 5948.9 5600.7 5847.3 5850.7 5857.6 5867.7 5946.9 5972.9 5988.8 5997.7 6007.8 5622.4 5762.9 5793.8 5813.6 5842.9 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - PROCESS | 15744 | [#5] yui.html Cycles:180 Average:20813.36 Median:4866.15 stddev:28667.32 (589.1%) stddev-sans-first:28708.40 08:57:36 INFO - PROCESS | 15744 | Values: 813.8 855.6 861.0 883.3 904.1 15784.4 17127.9 17151.7 17169.0 17180.3 15671.1 15942.9 15966.1 15972.1 16005.2 722.2 846.0 858.9 863.8 864.9 871.6 875.3 881.4 881.6 881.9 909.9 916.6 917.7 922.5 922.9 750.8 755.8 756.6 890.0 905.1 660.6 661.1 661.2 661.3 665.9 14395.9 14407.6 14446.7 14447.0 14463.6 14656.2 14977.3 15248.7 15268.4 15270.2 12472.5 12509.6 12665.7 12759.6 12764.5 4603.6 4658.3 4667.2 4686.9 4785.7 85021.0 85628.0 86107.6 87015.6 87095.6 12348.6 12368.9 12369.8 12561.6 12649.7 22681.2 22732.9 22765.5 22798.6 22801.5 8935.6 8950.3 9057.1 9208.2 9211.6 4713.1 4873.2 4876.8 4969.3 5090.2 3564.8 3655.6 3659.1 3677.8 3679.1 626.2 651.7 659.5 661.0 670.8 728.0 728.5 729.6 731.4 747.2 9179.2 9577.4 9698.4 9704.1 9721.0 612.8 631.8 684.2 690.4 728.9 4683.0 4688.2 4828.6 4852.9 4859.1 69345.0 76936.2 97664.5 99669.0 101347.0 97062.5 98697.5 98777.0 99157.0 99617.0 88183.0 89217.1 89931.6 90678.0 91195.5 9958.3 10259.0 10277.9 10392.0 10395.4 51793.5 52287.7 52380.7 53036.7 53801.2 50079.7 77552.0 78507.0 78570.0 78570.6 12898.8 13088.2 13374.2 14059.4 14343.4 15229.8 15562.7 15713.5 15990.4 16946.2 13172.5 13726.7 13966.4 14430.5 14764.0 14120.3 14337.6 14710.6 14780.7 14927.3 13602.8 13787.2 13953.4 13973.4 14016.6 12057.9 12114.0 12249.6 12393.1 12448.8 11828.0 11958.6 11996.9 12211.2 12239.6 08:57:36 INFO - PROCESS | 15744 | -------- Summary: end -------- 08:57:36 INFO - PROCESS | 15744 | 08:57:36 INFO - TEST-INFO | 15744: exit 0 08:57:36 INFO - TEST-OK | dromaeo_css | took 1112129ms 08:57:36 INFO - TEST-START | kraken 08:57:36 INFO - operating with platform_type : linux_ 08:57:36 INFO - Initialising browser for kraken test... 08:57:36 INFO - TEST-INFO | started process 15931 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ0TZCF/profile http://localhost:43449/getInfo.html) 08:57:40 INFO - PROCESS | 15931 | __metrics Screen width/height:1600/1200 08:57:40 INFO - PROCESS | 15931 | colorDepth:24 08:57:40 INFO - PROCESS | 15931 | Browser inner width/height: 1024/697 08:57:40 INFO - PROCESS | 15931 | __metrics 08:57:41 ERROR - PROCESS | 15931 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:57:42 INFO - TEST-INFO | 15931: exit 0 08:57:42 INFO - Browser initialized. 08:57:42 INFO - Running cycle 1/1 for kraken test... 08:57:42 INFO - TEST-INFO | started process 16021 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ0TZCF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:54 INFO - PROCESS | 16021 | RSS: Main: 145145856 08:57:54 INFO - PROCESS | 16021 | 08:57:56 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43449/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:57 INFO - PROCESS | 16021 | RSS: Main: 195072000 08:57:57 INFO - PROCESS | 16021 | 08:58:00 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43449/tests/kraken/driver.html?testName=audio-dft) 08:58:00 INFO - PROCESS | 16021 | RSS: Main: 192413696 08:58:00 INFO - PROCESS | 16021 | 08:58:03 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43449/tests/kraken/driver.html?testName=audio-fft) 08:58:04 INFO - PROCESS | 16021 | RSS: Main: 278999040 08:58:04 INFO - PROCESS | 16021 | 08:58:06 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43449/tests/kraken/driver.html?testName=audio-oscillator) 08:58:06 INFO - PROCESS | 16021 | RSS: Main: 188022784 08:58:06 INFO - PROCESS | 16021 | 08:58:08 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43449/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:58:09 INFO - PROCESS | 16021 | RSS: Main: 188051456 08:58:09 INFO - PROCESS | 16021 | 08:58:12 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43449/tests/kraken/driver.html?testName=imaging-darkroom) 08:58:13 INFO - PROCESS | 16021 | RSS: Main: 222720000 08:58:13 INFO - PROCESS | 16021 | 08:58:16 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43449/tests/kraken/driver.html?testName=imaging-desaturate) 08:58:16 INFO - PROCESS | 16021 | RSS: Main: 223698944 08:58:16 INFO - PROCESS | 16021 | 08:58:19 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43449/tests/kraken/driver.html?testName=json-parse-financial) 08:58:20 INFO - PROCESS | 16021 | RSS: Main: 222461952 08:58:20 INFO - PROCESS | 16021 | 08:58:21 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43449/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:58:22 INFO - PROCESS | 16021 | RSS: Main: 201039872 08:58:22 INFO - PROCESS | 16021 | 08:58:24 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:58:24 INFO - PROCESS | 16021 | RSS: Main: 201740288 08:58:24 INFO - PROCESS | 16021 | 08:58:26 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:58:27 INFO - PROCESS | 16021 | RSS: Main: 210526208 08:58:27 INFO - PROCESS | 16021 | 08:58:29 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:58:30 INFO - PROCESS | 16021 | RSS: Main: 213741568 08:58:30 INFO - PROCESS | 16021 | 08:58:33 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:58:33 INFO - PROCESS | 16021 | RSS: Main: 215695360 08:58:33 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | Cycle 1(1): loaded http://localhost:43449/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43449/tests/kraken/driver.html?testName=ai-astar) 08:58:35 INFO - PROCESS | 16021 | RSS: Main: 258097152 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | __start_tp_report 08:58:35 INFO - PROCESS | 16021 | _x_x_mozilla_page_load 08:58:35 INFO - PROCESS | 16021 | _x_x_mozilla_page_load_details 08:58:35 INFO - PROCESS | 16021 | |i|pagename|runs| 08:58:35 INFO - PROCESS | 16021 | |0;ai-astar;111;104;109;109;109;108;109;108;109;111 08:58:35 INFO - PROCESS | 16021 | |1;audio-beat-detection;149;124;132;169;135;124;156;125;156;130 08:58:35 INFO - PROCESS | 16021 | |2;audio-dft;160;160;161;161;170;160;159;162;159;159 08:58:35 INFO - PROCESS | 16021 | |3;audio-fft;75;78;76;128;75;75;75;76;104;76 08:58:35 INFO - PROCESS | 16021 | |4;audio-oscillator;84;84;120;86;84;86;118;85;85;128 08:58:35 INFO - PROCESS | 16021 | |5;imaging-gaussian-blur;117;117;116;117;116;117;116;117;117;116 08:58:35 INFO - PROCESS | 16021 | |6;imaging-darkroom;146;147;145;146;147;146;145;146;146;145 08:58:35 INFO - PROCESS | 16021 | |7;imaging-desaturate;94;96;95;94;95;94;94;94;94;94 08:58:35 INFO - PROCESS | 16021 | |8;json-parse-financial;50;50;49;50;50;51;50;50;50;50 08:58:35 INFO - PROCESS | 16021 | |9;json-stringify-tinderbox;61;60;59;60;60;60;59;59;58;59 08:58:35 INFO - PROCESS | 16021 | |10;stanford-crypto-aes;76;76;77;77;81;80;77;76;131;77 08:58:35 INFO - PROCESS | 16021 | |11;stanford-crypto-ccm;120;125;120;114;115;129;125;126;138;127 08:58:35 INFO - PROCESS | 16021 | |12;stanford-crypto-pbkdf2;155;155;156;155;153;154;157;154;153;151 08:58:35 INFO - PROCESS | 16021 | |13;stanford-crypto-sha256-iterative;62;63;62;63;62;68;62;60;60;63 08:58:35 INFO - PROCESS | 16021 | __end_tp_report 08:58:35 INFO - PROCESS | 16021 | __start_cc_report 08:58:35 INFO - PROCESS | 16021 | _x_x_mozilla_cycle_collect,1566 08:58:35 INFO - PROCESS | 16021 | __end_cc_report 08:58:35 INFO - PROCESS | 16021 | __startTimestamp1467647915639__endTimestamp 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | ------- Summary: start ------- 08:58:35 INFO - PROCESS | 16021 | Number of tests: 14 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:1.95 (1.8%) stddev-sans-first:1.88 08:58:35 INFO - PROCESS | 16021 | Values: 111.0 104.0 109.0 109.0 109.0 108.0 109.0 108.0 109.0 111.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:142.00 stddev:16.19 (11.4%) stddev-sans-first:16.84 08:58:35 INFO - PROCESS | 16021 | Values: 149.0 124.0 132.0 169.0 135.0 124.0 156.0 125.0 156.0 130.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#2] audio-dft Cycles:10 Average:161.10 Median:160.50 stddev:3.28 (2.0%) stddev-sans-first:3.46 08:58:35 INFO - PROCESS | 16021 | Values: 160.0 160.0 161.0 161.0 170.0 160.0 159.0 162.0 159.0 159.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#3] audio-fft Cycles:10 Average:83.80 Median:75.50 stddev:17.91 (23.7%) stddev-sans-first:18.71 08:58:35 INFO - PROCESS | 16021 | Values: 75.0 78.0 76.0 128.0 75.0 75.0 75.0 76.0 104.0 76.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:84.50 stddev:18.13 (21.5%) stddev-sans-first:18.70 08:58:35 INFO - PROCESS | 16021 | Values: 84.0 84.0 120.0 86.0 84.0 86.0 118.0 85.0 85.0 128.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:58:35 INFO - PROCESS | 16021 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:58:35 INFO - PROCESS | 16021 | Values: 146.0 147.0 145.0 146.0 147.0 146.0 145.0 146.0 146.0 145.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 08:58:35 INFO - PROCESS | 16021 | Values: 94.0 96.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 08:58:35 INFO - PROCESS | 16021 | Values: 50.0 50.0 49.0 50.0 50.0 51.0 50.0 50.0 50.0 50.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.71 08:58:35 INFO - PROCESS | 16021 | Values: 61.0 60.0 59.0 60.0 60.0 60.0 59.0 59.0 58.0 59.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:77.00 stddev:17.02 (22.1%) stddev-sans-first:17.88 08:58:35 INFO - PROCESS | 16021 | Values: 76.0 76.0 77.0 77.0 81.0 80.0 77.0 76.0 131.0 77.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.50 stddev:7.06 (5.6%) stddev-sans-first:7.35 08:58:35 INFO - PROCESS | 16021 | Values: 120.0 125.0 120.0 114.0 115.0 129.0 125.0 126.0 138.0 127.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:155.00 stddev:1.70 (1.1%) stddev-sans-first:1.79 08:58:35 INFO - PROCESS | 16021 | Values: 155.0 155.0 156.0 155.0 153.0 154.0 157.0 154.0 153.0 151.0 08:58:35 INFO - PROCESS | 16021 | 08:58:35 INFO - PROCESS | 16021 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:2.22 (3.6%) stddev-sans-first:2.35 08:58:35 INFO - PROCESS | 16021 | Values: 62.0 63.0 62.0 63.0 62.0 68.0 62.0 60.0 60.0 63.0 08:58:35 INFO - PROCESS | 16021 | -------- Summary: end -------- 08:58:35 INFO - PROCESS | 16021 | 08:58:36 INFO - TEST-INFO | 16021: exit 0 08:58:36 INFO - TEST-OK | kraken | took 59899ms 08:58:36 INFO - SUITE-END | took 1172s 08:58:36 INFO - Completed test suite (00:19:32) 08:58:36 INFO - javascript benchmark 08:58:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3310.9503357449644, 3315.220923083075, 3335.1495368680985, 3347.7489188310874, 3355.5973283206017, 5507.531859791918, 5596.524295434882, 5597.0, 5616.073347897597, 5622.381538030819, 5471.37079235888, 5534.944650553493, 5571.609987300891, 5580.888382232363, 5602.047651899176, 4921.261810728383, 4987.202047672373, 5010.899782004376, 5037.093323201822, 5076.923846142311, 4898.167311557026, 5035.823746168885, 5066.138756411419, 5077.238414237875, 5077.822276220333, 4991.800327986876, 5006.4492905780335, 5019.673721208128, 5037.546620804127, 5086.160783470723, 5102.515261050195, 5115.66748161372, 5130.53825155734, 5135.691858488465, 5161.870953226181, 4842.080004799077, 4922.532359425849, 4959.553640172366, 5038.546530812245, 5042.369703787027, 5339.385970613391, 5347.41178470368, 5355.437679043722, 5365.731713414352, 5384.030874442598, 5824.446677565625, 5876.97061514694, 5904.5571582131515, 5904.61619994699, 5910.645361278337, 5797.739101740432, 5898.498627616657, 5940.0, 5947.910781338283, 6015.187949626832, 5582.888342233173, 5615.9157612635845, 5623.353314368859, 5626.493615574577, 5630.746616402272, 5949.077892926603, 6085.174447665682, 6091.969540152271, 6093.173869005767, 6131.14164017038, 5765.164051212634, 5792.391798861143, 5792.971035144881, 5896.174535565025, 5896.3219229788265, 5946.286445626552, 5951.970240148771, 5952.345242023374, 5975.850603734942, 5986.5809393342915, 5631.211630371751, 5841.474267315962, 5904.527637788967, 5912.201852749909, 5919.437653422918, 5890.410958904161, 5895.734691938787, 5899.911501327483, 5912.142739302777, 5934.732937017759, 4524.23088075028, 4525.502194758574, 4528.162289976365, 4534.093181363741, 4554.453465584151, 3961.7622942623534, 3989.6608788252743, 4014.3577027676015, 4037.414574886625, 4072.9796351018053, 5647.971760141255, 5668.149777533321, 5672.177534257592, 5672.262605861211, 5693.430656934356, 5960.552958528127, 5962.85092872673, 5969.52243820502, 5973.581849270597, 6008.939910600838, 5484.095124304525, 5556.583256255797, 5603.215549823028, 5603.915941260884, 5607.775688972477, 5064.1137800885435, 5092.694438333712, 5092.719900405513, 5097.974510127426, 5098.490150984871, 6082.330943596201, 6088.6042407243385, 6091.360407157274, 6093.360197179321, 6094.085887116968, 5815.5929084963645, 5880.294364676266, 5936.8812623748145, 5941.049432090845, 5999.460048595647, 6180.196574445294, 6185.0, 6188.597741146811, 6194.628322300677, 6199.28708198563, 5648.15277708347, 5859.974504461617, 5875.529957603296, 5886.38192989747, 5894.351621321565, 5861.32594751609, 5880.647161170344, 5909.674967876808, 5975.014122669711, 5975.9103613446705, 5813.82558523245, 5848.824535263949, 5858.794942177004, 5858.8242352729485, 5859.326177489644, 5591.692456914909, 5692.032354499662, 5764.337101233412, 5796.188533605215, 5817.214676018683, 5612.158176273591, 5689.573282003784, 5690.3456102548735, 5695.715214239354, 5727.656340619576, 5772.249607550992, 5779.844031193862, 5800.796972105956, 5803.013487707016, 5822.534197264294, 5521.558275338044, 5635.690037048002, 5674.829755107354, 5695.7436915338085, 5711.114777209539], "value": 5460.777298483444, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.1704779213517, 654.9901751473728, 663.7013343995201, 664.6809531424913, 670.6780745242287, 32760.180995475097, 38566.8071659642, 38775.612243877586, 39191.0, 39363.21273574527, 48323.0, 48710.51289487104, 48832.75583622086, 48858.755706221506, 48908.02183956319, 8454.408191426603, 9092.136247056536, 9257.16685498305, 9265.073492650714, 10861.456927153651, 10617.840732388982, 10999.835002474969, 11006.0, 11015.889841101607, 11037.779244415147, 11143.275687080355, 11200.775984480306, 11202.943985280062, 11211.719707007309, 11214.66356009321, 8556.657733690676, 10932.344059356465, 11025.61410350638, 11222.0, 11233.887661123448, 10626.787464250749, 11029.44852757359, 11059.336439813636, 11066.612668556643, 11073.723156921142, 47399.76300118512, 47761.76119119417, 47920.520794791955, 47991.0, 47996.760016200045, 3968.2460332536725, 3986.66113380363, 4007.1184339445276, 4499.392582001421, 4620.376249206348, 35561.82219088888, 36298.0, 36318.27363452716, 36363.272734545164, 37975.620243797486, 3947.1710940702487, 4008.078142027321, 4014.6186112319283, 4022.6781857451333, 4028.47629808126, 312097.0, 386558.0, 387331.0, 387486.0, 388365.0, 39164.80417597894, 40190.799046004584, 72518.27481725115, 73299.26700732924, 73473.0, 41908.161836763094, 41986.37020444696, 42139.15721685549, 42156.789216054334, 42165.57834421678, 18117.0, 18380.908095459436, 18383.080845957647, 18385.356512522267, 18393.90803046003, 13970.860291396955, 14120.082194657314, 14146.151230925972, 14151.504697335544, 14488.492902748563, 9274.721758347261, 9285.25717942563, 9288.489133097744, 9306.162445379949, 9371.765705857408, 3575.0, 3618.3306088373733, 3678.7976661283888, 3692.2800053989213, 3698.130939229279, 1075.714935542082, 1105.812011957953, 1125.5441546173813, 1126.6086383316597, 1193.76721539299, 34984.825075874454, 35044.47433288502, 35064.82467587646, 35073.29853402969, 35287.4706879397, 1151.8230544271335, 1209.9879001209874, 1227.5948936850818, 1245.7633049720523, 1253.5173958026044, 12754.553590624284, 16266.349346026027, 17338.826611733723, 18021.549461263574, 18456.630867382846, 625349.0, 631255.0, 632803.0, 634084.0, 638587.0, 64926.0, 65455.0, 65505.0, 65622.34377656163, 65657.34342656512, 1389.124851343647, 1450.4778279819257, 1452.5351887396143, 1470.7058588282173, 1508.7812267221411, 34004.0, 34095.65904341024, 34231.31537369189, 34771.30457390787, 34930.47604285989, 53003.20495192498, 53455.73272133614, 53557.196642049625, 53587.73206133944, 53825.19262210991, 341598.0, 348426.0, 348686.0, 348979.0, 349011.0, 26585.670716464487, 29078.273043173245, 29800.850995744884, 30528.389432210788, 30841.8457907709, 48031.75984120057, 48733.0, 48980.0, 49486.257706133714, 49844.25233621571, 39887.40168897411, 40753.1849363017, 41062.1787564253, 41406.79296603498, 42221.788891056574, 40720.59279407168, 40759.38860917148, 40939.7953010233, 41029.0, 42046.36930445985, 36882.8155859219, 43687.78156109199, 45047.549524504335, 45132.32301515545, 47194.29208561943, 40180.598194017686, 40457.797711011255, 40884.79557602193, 41325.1734965293, 41964.58035419607, 29733.0, 30381.0, 30530.694693053672, 30712.539311910787, 30855.382892342477], "value": 20465.748649360787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.49330556679206, 429.31738535728186, 429.67988848308005, 431.8704388683396, 438.79595987865656, 19149.329773457932, 20322.1871125155, 20940.685889711647, 21822.672659910113, 21824.454388640293, 19941.40175794725, 20294.594108117828, 20455.59088818223, 20467.590648187066, 20614.072366743494, 5576.0, 5815.970920145393, 5844.678542680151, 5851.093080572518, 5860.794872179475, 5603.859903502404, 5646.068398714207, 5762.1068734346245, 5891.469767720904, 5895.911561326562, 5918.171455996164, 5928.6739229342365, 5929.8814023719715, 5942.049272116462, 5943.465088142067, 5830.3586605473365, 5837.941620583803, 5925.822225333247, 5951.375105613892, 5967.16459695643, 5633.380328163898, 5699.629524080921, 5725.828225153252, 5729.684867332295, 5778.219940308047, 13748.793768093487, 14059.929700351535, 14059.929700351535, 14065.859341406558, 15187.696246075016, 2360.079568968104, 2380.143148466551, 2467.716212635534, 2630.1320564213956, 2690.166048524944, 10039.548220330104, 10204.183665306758, 10489.737756556147, 13271.535496257584, 13551.728965420734, 1987.4633848860726, 2001.449601359623, 2010.3566858605252, 2096.3920463065692, 2154.450615093141, 62738.686306568175, 69928.0, 84897.15102848955, 85125.0, 89150.55424722836, 10990.120790336754, 11455.59905403307, 11568.24806387582, 11634.65096047103, 11881.524739010516, 16448.671026579403, 16537.586560335847, 16616.83383166177, 16633.66732665364, 16652.75020874688, 7547.584882831387, 9049.411788233743, 9077.863832042525, 9088.227500662519, 9245.768855778524, 8368.748937531884, 8599.484030958038, 9223.49270790113, 9334.206592439583, 9346.813063738688, 4863.829765958245, 4956.430010548761, 5013.523715247045, 5015.0, 5028.974855125701, 1950.4538729155859, 1958.0, 1995.6507611167988, 2007.217185297735, 2032.9695054574192, 972.4748635736764, 1036.388530766851, 1085.945702714877, 1101.146611376174, 1121.4785124916953, 19842.70235946462, 19871.701924471145, 20000.299989500298, 20062.498437538885, 20122.496937576387, 1041.2971244409994, 1048.6801525534634, 1080.3625860742043, 1085.6634443322596, 1104.7403860092872, 10822.729431764268, 11136.832947505794, 11251.324920504632, 11293.604723834625, 11303.434828258554, 479360.0, 485886.0, 490169.0, 491384.0, 504082.0, 80945.59527202326, 95756.0, 99387.50306248422, 101349.49325253474, 103308.0, 1070.4273213830563, 1101.9504122314354, 1108.1024370260116, 1108.51225460797, 1114.966551003455, 17583.560410989317, 17924.91037544804, 18003.695011734053, 18055.097245137953, 18461.0, 43239.35140972951, 43918.56081439145, 44388.77805610951, 44437.0, 45316.0, 56940.43059569351, 58247.41752582589, 60662.0, 61718.382816173056, 62430.37569624246, 8263.545504997282, 8315.792105197177, 8402.495850249004, 8446.788830279293, 8514.574271286287, 9255.53722313895, 12110.878891210976, 12394.380280986095, 12585.748285034431, 12654.114212005072, 10719.517621707198, 10757.677269681923, 10894.727631809268, 11019.669409917715, 11165.49755260999, 10730.946345268223, 10733.409662468639, 10748.731281718019, 10942.0, 11002.174836887425, 10784.460776960963, 10791.568337266735, 10871.891281087088, 10945.945270273598, 11405.372704501, 8286.502809831429, 8321.875171872547, 8392.328613710766, 8419.70531031411, 8525.74422767318, 7953.32396746283, 9353.345265831598, 10607.469626518488, 10939.124870010539, 11060.944695276472], "value": 9326.735581775756, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.8438171801095, 1563.1480842940593, 1587.809462864456, 1774.73378993151, 1946.6009468059058, 8675.43609665372, 9045.728628141158, 9064.138906803852, 9341.39280946738, 9363.90636093639, 9093.454392736441, 9291.953540232289, 9293.814123717522, 9297.163255307021, 9299.02360252174, 2084.687296905469, 2317.7450480447137, 2331.3122628824453, 2359.4573248152933, 2399.78401943825, 2443.6701045358914, 2451.7548245175517, 2464.987675061622, 2471.7404672509397, 2477.5168842075805, 2368.0409434179187, 2396.6405039244164, 2398.796102331304, 2399.436132508859, 2400.567897778399, 2013.4664313956814, 2302.24026071396, 2374.4301367671806, 2376.4534157143953, 2393.198278576679, 2377.405648587853, 2379.9524009519887, 2381.535600557892, 2382.285314405671, 2414.2757172848073, 9568.712938611852, 9610.327277090606, 9627.277954153467, 9627.711168664951, 9636.662716804949, 5171.715555644438, 5371.435999220064, 5373.59698022646, 5374.6237763356585, 5378.220158077057, 3211.486162214058, 3297.950530742041, 3356.848941797625, 3398.235397035672, 3404.914877128067, 1078.0567003871613, 1086.630545614487, 1105.336797921249, 1106.0819519798547, 1118.1893127482592, 13641.86358136416, 13737.725245495134, 13740.725185496234, 13740.862591374058, 13753.38109785062, 4619.607333376701, 4634.119517291704, 4708.858734237979, 4719.764011799465, 4740.051989602093, 11306.378149201708, 11324.830127548094, 11337.43312834355, 11369.31784092957, 11371.715707107389, 4836.733979631154, 5535.916961245504, 5543.36251331094, 5544.528715059265, 5546.334439867242, 4722.763861806896, 4859.198232291632, 4861.392325959255, 4879.121758083508, 4885.5358740919555, 4011.6389524942897, 4023.796861875819, 4025.0742329264435, 4035.1929614077303, 4038.9798051009557, 1231.2181764579377, 1259.030546479206, 1261.2621616354352, 1269.2130878854985, 1270.4791035675328, 929.6049179098856, 991.8859331176862, 1030.314840630987, 1062.654637242899, 1068.8770791358938, 6341.841453963595, 6417.679116044179, 6421.8073457796345, 6503.089567460559, 6512.934870651233, 420.7223232666487, 421.3532228029959, 422.1851825975835, 429.20811103513967, 435.0993443571776, 4289.549597292302, 4456.17560751262, 4511.616512596401, 4552.7495987720995, 4558.202314594932, 11507.88492115085, 11930.940345298392, 12009.339486328161, 12027.27836329823, 12046.698832529082, 11689.123315751352, 11724.941375293069, 11777.116716246315, 11781.234219775688, 11789.056875449944, 8371.414001019992, 8688.348373871984, 8697.869531957025, 8783.0, 8893.99999999987, 6555.475561955128, 6654.534182607171, 6726.630035348103, 6771.492138089761, 6787.728490860508, 10428.791424171322, 10715.142788576739, 10774.24580279373, 10834.566617335533, 10929.83605245906, 10812.0, 10927.56289748433, 10940.781184376108, 10952.288101273392, 10988.780224395307, 7380.5940673263485, 7585.810354740955, 7908.5650289234645, 8027.317677997436, 8140.185981402049, 9023.548822558714, 9373.20327772147, 9395.436273823592, 9407.341486095907, 9421.905780942378, 8166.142555031636, 8329.708460203863, 8366.205210504992, 8423.663053477792, 8512.361572881935, 7489.737859174903, 7683.731069412544, 7707.0, 7738.7678369649, 7765.883511747215, 7359.0, 7371.520851144658, 7420.220876807857, 7421.443391745533, 7545.584992825447, 5263.210518422115, 5299.534567827244, 5537.889242215053, 5548.112302031655, 5563.693996830213, 5616.578756593353, 5619.072852979212, 5624.3250809903075, 5701.315842098975, 5720.342160651412], "value": 4755.671642527206, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.78859010548206, 339.7231256525931, 342.4469481786915, 346.50276852716337, 347.98086105264207, 5511.3937466878615, 5538.833834984946, 5610.102383618621, 5641.164008474241, 5688.943110568893, 5557.166425036246, 5566.415526369724, 5568.86077848053, 5611.494965453108, 5614.831555053344, 4348.956510434887, 4349.652027837781, 4355.259605867002, 4403.4495688039, 4418.5581441855875, 4236.191986520309, 4349.195398851222, 4387.583179597933, 4429.31685959335, 4443.84446544371, 4265.381519679645, 4324.286492728696, 4325.307950727884, 4354.281543545311, 4355.978220108911, 4294.871153865389, 4294.935575966332, 4296.957030429702, 4300.290452075404, 4307.698461107724, 3696.537932758405, 3697.7781333120097, 3808.733388662795, 3813.218290250492, 3813.561440434358, 5300.727825321895, 5316.0, 5319.228711836762, 5329.920051199235, 5333.413324534298, 4873.220284754458, 4886.267059941002, 4899.534544218293, 4910.680805747615, 4911.336969509106, 5482.341064583674, 5777.815547812688, 5814.680192589406, 5832.591718579701, 5834.941650583524, 4140.399642051916, 4148.398482220091, 4166.729162604421, 4168.916081818718, 4176.227397931392, 6046.455818976313, 6097.939020609781, 6101.389861013908, 6105.847353816146, 6124.755009799603, 4178.059936514321, 4249.171411574775, 4261.529772228576, 4291.334843099325, 4297.119090586422, 4284.207421627009, 4288.335308027198, 4295.398644189816, 4296.828126874952, 4303.311470164758, 4886.95113048865, 4889.828855990023, 4892.633052521075, 4904.950950490521, 4932.0, 5590.965671350813, 5717.599768016282, 5858.384869588717, 5884.882302354014, 5891.793787217427, 1449.746294398476, 1474.7390980711336, 1480.659448326891, 1481.6740317130213, 1484.5249520153448, 903.590398977597, 908.2506931781306, 924.5885580916562, 931.0410277414275, 943.3208090175063, 5127.461906356378, 5135.820246291362, 5145.170156413958, 5157.871053223625, 5185.714785686748, 5343.625946183807, 5379.946200538023, 5385.946140538623, 5421.0, 5433.8369848904595, 5073.695578265316, 5084.491550844886, 5087.211482220262, 5369.892602148013, 5820.76716931318, 3199.5520627112223, 3212.5984251968503, 3221.0, 3222.5166225066428, 3224.4196044711707, 6129.84675383119, 6200.658963756947, 6245.219347581552, 6260.906086408708, 6294.9226755170685, 6109.969450152721, 6122.265328160649, 6133.049377346519, 6140.508759299245, 6176.320604733476, 6190.721417536131, 6266.276773887217, 6272.404121608532, 6294.4020318070625, 6296.212973378328, 5820.388859169726, 6050.5764596479585, 6057.879292330933, 6063.363346848518, 6083.482903953037, 5951.7619295227705, 5978.923793717173, 5978.940210597838, 5982.102684597346, 6046.213992181164, 5994.28068631767, 6273.9372606273355, 6277.905831412441, 6296.811095667137, 6302.5588208826775, 4227.070044590185, 4256.446661933929, 4262.275413179829, 4266.786660666892, 4276.401303817406, 5745.971270143623, 5811.0, 6017.939820601738, 6078.939210607838, 6088.391160883876, 5929.140274660236, 5948.910766338422, 5965.0, 5971.4327138921735, 5991.940080599313, 5873.647581145145, 5926.0, 5930.199423077829, 5943.583949123693, 5948.8810223796145, 5600.663960162403, 5847.327557330961, 5850.736716847837, 5857.560682948882, 5867.706614669164, 5946.881062378641, 5972.850678732892, 5988.790392336247, 5997.70011499432, 6007.789727359522, 5622.409646987007, 5762.855928601818, 5793.768249269982, 5813.56398270123, 5842.941570584239], "value": 4421.808477000301, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.7884150120967, 855.5551113421024, 860.9741707748767, 883.284539522986, 904.0868722590182, 15784.36862525499, 17127.91436042821, 17151.656966860653, 17169.0, 17180.3127874885, 15671.138087405188, 15942.920285398586, 15966.121863297514, 15972.121533315663, 16005.199740012982, 722.1658983873634, 846.0312941681801, 858.8926384201975, 863.7667829685981, 864.9264812490945, 871.5729292646589, 875.2560323724847, 881.4314766975297, 881.6297155194801, 881.8633111867671, 909.8908131024286, 916.5554705967601, 917.6833992272656, 922.4603606889978, 922.9353945223837, 750.8122969257686, 755.765712629084, 756.606564586412, 890.0387581412059, 905.1310741687987, 660.6366498425847, 661.0579923608839, 661.206552137437, 661.256086902235, 665.9167604049494, 14395.856041439556, 14407.639809004859, 14446.711065778625, 14446.988710790249, 14463.566093017227, 14656.193909334983, 14977.326020329003, 15248.695026099527, 15268.38926442941, 15270.16014119223, 12472.50109995599, 12509.624711258677, 12665.683357916034, 12759.61721148367, 12764.489420423171, 4603.562661547181, 4658.301254811805, 4667.183242932473, 4686.929696054562, 4785.712857228577, 85021.0, 85628.0, 86107.56946215229, 87015.56492217562, 87095.56452217762, 12348.567800126952, 12368.938155309166, 12369.752604947851, 12561.56034538805, 12649.683757906127, 22681.2061577844, 22732.886335568215, 22765.544689106126, 22798.632082075128, 22801.543969120525, 8935.553222338987, 8950.328725345495, 9057.09429057089, 9208.171264586219, 9211.63153473867, 4713.104510143062, 4873.244647079709, 4876.804927802848, 4969.3291405659775, 5090.2364645303505, 3564.8395822188063, 3655.6161603031833, 3659.0852286928266, 3677.8161091945294, 3679.1170119171743, 626.2078470734524, 651.7230177174682, 659.475716805141, 660.986780264392, 670.8389986403228, 727.9818004549928, 728.5373787644884, 729.5877829026583, 731.4294850016995, 747.1781040855125, 9179.219766319937, 9577.377470464537, 9698.418094914328, 9704.126628603319, 9721.0, 612.8100288910452, 631.8009826904511, 684.1926526698544, 690.4234963805279, 728.8578727148262, 4683.0, 4688.179568575554, 4828.565429111397, 4852.927206091911, 4859.052484765508, 69345.0, 76936.23063769403, 97664.51167744258, 99669.0, 101347.0, 97062.51468742611, 98697.50651246698, 98777.0, 99157.0, 99617.0, 88183.0, 89217.10782892218, 89931.55034224917, 90678.0, 91195.5440222808, 9958.253131015348, 10259.0, 10277.9486102569, 10392.0, 10395.428251446243, 51793.48206517887, 52287.73856130847, 52380.7380963108, 53036.73481632567, 53801.19298210452, 50079.74960125176, 77552.0, 78507.0, 78570.0, 78570.6071469639, 12898.80651790205, 13088.214707117604, 13374.197548147142, 14059.367328470038, 14343.426262949366, 15229.771553426928, 15562.688746224785, 15713.528594142193, 15990.357573322417, 16946.152692365085, 13172.47310107585, 13726.725465490834, 13966.441342346192, 14430.494932677306, 14764.0, 14120.293985300488, 14337.64155896111, 14710.63223419423, 14780.704385912437, 14927.253637318308, 13602.795958060835, 13787.241701706502, 13953.441862325393, 13973.371198295898, 14016.64958376008, 12057.939710301393, 12114.030877529953, 12249.632511024683, 12393.070519710878, 12448.75102497963, 11828.0, 11958.64124076279, 11996.880031199576, 12211.206271592318, 12239.571614993789], "value": 7865.479757889951, "unit": "score"}], "name": "dromaeo_css", "value": 7460.454030289915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 109.0, 109.0, 109.0, 108.0, 109.0, 108.0, 109.0, 111.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 132.0, 169.0, 135.0, 124.0, 156.0, 125.0, 156.0, 130.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 170.0, 160.0, 159.0, 162.0, 159.0, 159.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 128.0, 75.0, 75.0, 75.0, 76.0, 104.0, 76.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 120.0, 86.0, 84.0, 86.0, 118.0, 85.0, 85.0, 128.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 51.0, 50.0, 50.0, 50.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 59.0, 58.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 77.0, 81.0, 80.0, 77.0, 76.0, 131.0, 77.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 120.0, 114.0, 115.0, 129.0, 125.0, 126.0, 138.0, 127.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 156.0, 155.0, 153.0, 154.0, 157.0, 154.0, 153.0, 151.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 62.0, 68.0, 62.0, 60.0, 60.0, 63.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1479.5, "alertThreshold": 2.0}]} 08:58:36 INFO - Return code: 0 08:58:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:58:36 ERROR - # TBPL SUCCESS # 08:58:36 INFO - Running post-action listener: _resource_record_post_action 08:58:36 INFO - ##### 08:58:36 INFO - ##### Finished run-tests step (success) 08:58:36 INFO - ##### 08:58:36 INFO - Running post-run listener: _resource_record_post_run 08:58:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 487682048; Read time: 8; Write time: 362088 08:58:37 INFO - TinderboxPrint: CPU usage
13.1% 08:58:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:58:37 INFO - TinderboxPrint: I/O write bytes / time
487,682,048 / 362,088 08:58:37 INFO - TinderboxPrint: CPU idle
8,248.2 (86.9%) 08:58:37 INFO - TinderboxPrint: CPU user
1,198.7 (12.6%) 08:58:37 INFO - TinderboxPrint: Swap in / out
0 / 0 08:58:37 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 215134208; Read time: 0; Write time: 230224 08:58:37 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254193664; Read time: 8; Write time: 124272 08:58:37 INFO - Running post-run listener: _upload_blobber_files 08:58:37 INFO - Blob upload gear active. 08:58:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:58:37 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 08:58:37 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:58:37 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:58:37 INFO - (blobuploader) - INFO - Open directory for files ... 08:58:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:58:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:38 INFO - (blobuploader) - INFO - Done attempting. 08:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:58:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:42 INFO - (blobuploader) - INFO - Done attempting. 08:58:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:58:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:58:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:42 INFO - (blobuploader) - INFO - Done attempting. 08:58:42 INFO - (blobuploader) - INFO - Iteration through files over. 08:58:42 INFO - Return code: 0 08:58:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:58:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:58:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1a3c9b5d7ba18c656ffc54aa511a94980e5f5f2b70af22f44e969410aed6bf088be10aad6cefee3f1bda6f63ccea4ebdc90b8f62f087ec65d582781a708d052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/713e7fee67fa052349f187519d474c0447f54cfd44b187f54266b178707acef70a94ff90428dd292f346a4c97d7411d063a03fea25f0a8aba00cbd9297e5455a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/938d4276314511790448e78111c856de5d6ebdddbd5ac61539f2a83d5ee145d8b93758fa958c61b670d5302ee59d4399b0040a00b0dd5a2dc523478c7cb45ff7"} 08:58:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:58:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:58:42 INFO - Contents: 08:58:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1a3c9b5d7ba18c656ffc54aa511a94980e5f5f2b70af22f44e969410aed6bf088be10aad6cefee3f1bda6f63ccea4ebdc90b8f62f087ec65d582781a708d052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/713e7fee67fa052349f187519d474c0447f54cfd44b187f54266b178707acef70a94ff90428dd292f346a4c97d7411d063a03fea25f0a8aba00cbd9297e5455a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/938d4276314511790448e78111c856de5d6ebdddbd5ac61539f2a83d5ee145d8b93758fa958c61b670d5302ee59d4399b0040a00b0dd5a2dc523478c7cb45ff7"} 08:58:42 INFO - Running post-run listener: copy_logs_to_upload_dir 08:58:42 INFO - Copying logs to upload dir... 08:58:42 INFO - mkdir: /builds/slave/test/build/upload/logs 08:58:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.764104 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-04 08:58:42.673730) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 08:58:42.676999) ========= 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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 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-Aurora/sha512/b1a3c9b5d7ba18c656ffc54aa511a94980e5f5f2b70af22f44e969410aed6bf088be10aad6cefee3f1bda6f63ccea4ebdc90b8f62f087ec65d582781a708d052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/713e7fee67fa052349f187519d474c0447f54cfd44b187f54266b178707acef70a94ff90428dd292f346a4c97d7411d063a03fea25f0a8aba00cbd9297e5455a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/938d4276314511790448e78111c856de5d6ebdddbd5ac61539f2a83d5ee145d8b93758fa958c61b670d5302ee59d4399b0040a00b0dd5a2dc523478c7cb45ff7"} build_url:https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011043 build_url: 'https://queue.taskcluster.net/v1/task/Zi8QMtubRTm4uWRP2apxvg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1a3c9b5d7ba18c656ffc54aa511a94980e5f5f2b70af22f44e969410aed6bf088be10aad6cefee3f1bda6f63ccea4ebdc90b8f62f087ec65d582781a708d052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/713e7fee67fa052349f187519d474c0447f54cfd44b187f54266b178707acef70a94ff90428dd292f346a4c97d7411d063a03fea25f0a8aba00cbd9297e5455a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/938d4276314511790448e78111c856de5d6ebdddbd5ac61539f2a83d5ee145d8b93758fa958c61b670d5302ee59d4399b0040a00b0dd5a2dc523478c7cb45ff7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 08:58:42.702105) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:58:42.702442) ========= 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=923d835db2ba3686ebe126b700000192-1467645325.252875-2060872873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004352 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 08:58:42.784375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 08:58:42.784696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 08:58:42.785009) ========= ========= Total master_lag: 0.19 =========