builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-035 starttime: 1467023432.47 results: success (0) buildid: 20160627004013 builduid: 23f3ae60fcd94072a6178f2a56f82a27 revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.468560) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.469543) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.470181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.486569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.487134) ========= 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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 03:30:32-- 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% 42.6M=0s 2016-06-27 03:30:32 (42.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098935 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.605242) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.605995) ========= 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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014047 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.659099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:32.659471) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev afcdd85399636b518887cc17aedbda8cc1c1f197 --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 afcdd85399636b518887cc17aedbda8cc1c1f197 --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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 03:30:32,793 truncating revision to first 12 chars 2016-06-27 03:30:32,793 Setting DEBUG logging. 2016-06-27 03:30:32,793 attempt 1/10 2016-06-27 03:30:32,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/afcdd8539963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 03:30:33,092 unpacking tar archive at: mozilla-aurora-afcdd8539963/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647986 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:33.410477) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:33.413828) ========= script_repo_revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:33.414302) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:33.414626) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:33.426840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-27 03:30:33.427153) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:33 INFO - MultiFileLogger online at 20160627 03:30:33 in /builds/slave/test 03:30:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:30:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:33 INFO - {'append_to_log': False, 03:30:33 INFO - 'base_work_dir': '/builds/slave/test', 03:30:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:33 INFO - 'branch': 'Mozilla-Aurora', 03:30:33 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:33 INFO - 'config_files': ('talos/linux_config.py',), 03:30:33 INFO - 'default_actions': ('clobber', 03:30:33 INFO - 'read-buildbot-config', 03:30:33 INFO - 'download-and-extract', 03:30:33 INFO - 'populate-webroot', 03:30:33 INFO - 'create-virtualenv', 03:30:33 INFO - 'install', 03:30:33 INFO - 'run-tests'), 03:30:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:33 INFO - 'download_minidump_stackwalk': True, 03:30:33 INFO - 'download_symbols': 'ondemand', 03:30:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:33 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:33 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:33 INFO - 'installer_path': 'installer.exe', 03:30:33 INFO - 'log_level': 'info', 03:30:33 INFO - 'log_name': 'talos', 03:30:33 INFO - 'log_to_console': True, 03:30:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:33 INFO - 'opt_config_files': (), 03:30:33 INFO - 'pip_index': False, 03:30:33 INFO - 'sps_profile': False, 03:30:33 INFO - 'sps_profile_interval': 0, 03:30:33 INFO - 'suite': 'dromaeojs-e10s', 03:30:33 INFO - 'system_bits': '32', 03:30:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:33 INFO - 'title': 'talos-linux64-ix-035', 03:30:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:33 INFO - 'use_talos_json': True, 03:30:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:33 INFO - 'work_dir': 'build'} 03:30:33 INFO - ##### 03:30:33 INFO - ##### Running clobber step. 03:30:33 INFO - ##### 03:30:33 INFO - Running pre-action listener: _resource_record_pre_action 03:30:33 INFO - Running main action method: clobber 03:30:33 INFO - rmtree: /builds/slave/test/build 03:30:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:34 INFO - Running post-action listener: _resource_record_post_action 03:30:34 INFO - ##### 03:30:34 INFO - ##### Finished clobber step (success) 03:30:34 INFO - ##### 03:30:34 INFO - ##### 03:30:34 INFO - ##### Running read-buildbot-config step. 03:30:34 INFO - ##### 03:30:34 INFO - Running pre-action listener: _resource_record_pre_action 03:30:34 INFO - Running main action method: read_buildbot_config 03:30:34 INFO - Using buildbot properties: 03:30:34 INFO - { 03:30:34 INFO - "project": "", 03:30:34 INFO - "product": "firefox", 03:30:34 INFO - "script_repo_revision": "production", 03:30:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:30:34 INFO - "repository": "", 03:30:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:30:34 INFO - "buildid": "20160627004013", 03:30:34 INFO - "stage_platform": "linux64-pgo", 03:30:34 INFO - "basedir": "/builds/slave/test", 03:30:34 INFO - "nightly_build": "True", 03:30:34 INFO - "buildnumber": 370, 03:30:34 INFO - "slavename": "talos-linux64-ix-035", 03:30:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:30:34 INFO - "platform": "ubuntu64_hw", 03:30:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:30:34 INFO - "branch": "mozilla-aurora", 03:30:34 INFO - "repo_path": "releases/mozilla-aurora", 03:30:34 INFO - "revision": "afcdd85399636b518887cc17aedbda8cc1c1f197", 03:30:34 INFO - "pgo_build": "True", 03:30:34 INFO - "builduid": "23f3ae60fcd94072a6178f2a56f82a27", 03:30:34 INFO - "slavebuilddir": "test" 03:30:34 INFO - } 03:30:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:30:34 INFO - Running post-action listener: _resource_record_post_action 03:30:34 INFO - ##### 03:30:34 INFO - ##### Finished read-buildbot-config step (success) 03:30:34 INFO - ##### 03:30:34 INFO - ##### 03:30:34 INFO - ##### Running download-and-extract step. 03:30:34 INFO - ##### 03:30:34 INFO - Running pre-action listener: _resource_record_pre_action 03:30:34 INFO - Running main action method: download_and_extract 03:30:34 INFO - mkdir: /builds/slave/test/build/tests 03:30:34 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')]} 03:30:34 INFO - https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:35 INFO - Downloaded 1361 bytes. 03:30:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:35 INFO - Using the following test package requirements: 03:30:35 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:35 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:30:35 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:30:35 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'jsshell-linux-x86_64.zip'], 03:30:35 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:30:35 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:35 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:30:35 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:30:35 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:30:35 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:30:35 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:35 INFO - https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:36 INFO - Downloaded 24516514 bytes. 03:30:36 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'] 03:30:36 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 03:30:38 INFO - Return code: 0 03:30:38 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 03:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:38 INFO - https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:39 INFO - Downloaded 24516514 bytes. 03:30:39 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'] 03:30:39 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 03:30:41 INFO - Return code: 0 03:30:41 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')]} 03:30:41 INFO - https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:43 INFO - Downloaded 10833369 bytes. 03:30:43 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'] 03:30:43 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 03:30:43 INFO - Return code: 0 03:30: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')]} 03:30:43 INFO - https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:30:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:30:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:30:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IiMFMX9EQxSo4QE2gcCdVg/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 03:30:46 INFO - Downloaded 63616255 bytes. 03:30:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:30:46 INFO - mkdir: /builds/slave/test/properties 03:30:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:30:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:30:46 INFO - Contents: 03:30:46 INFO - build_url:https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:30:46 INFO - Running post-action listener: _resource_record_post_action 03:30:46 INFO - Running post-action listener: set_extra_try_arguments 03:30:46 INFO - ##### 03:30:46 INFO - ##### Finished download-and-extract step (success) 03:30:46 INFO - ##### 03:30:46 INFO - ##### 03:30:46 INFO - ##### Running populate-webroot step. 03:30:46 INFO - ##### 03:30:46 INFO - Running pre-action listener: _resource_record_pre_action 03:30:46 INFO - Running main action method: populate_webroot 03:30:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:30:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:30:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:30:46 INFO - '--tppagecycles', 03:30:46 INFO - '7'], 03:30:46 INFO - 'tests': ['tsvgm']}}, 03:30:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:30:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:30:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:30:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:30:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['damp', 'tps']}, 03:30:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'tests': ['damp', 'tps']}, 03:30:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['dromaeo_dom']}, 03:30:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:30:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['a11yr', 03:30:46 INFO - 'ts_paint', 03:30:46 INFO - 'tpaint', 03:30:46 INFO - 'sessionrestore', 03:30:46 INFO - 'sessionrestore_no_auto_restore', 03:30:46 INFO - 'tabpaint']}, 03:30:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['tsvgx', 03:30:46 INFO - 'tsvgr_opacity', 03:30:46 INFO - 'tart', 03:30:46 INFO - 'tscrollx', 03:30:46 INFO - 'cart']}, 03:30:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:30:46 INFO - 'tsvgr_opacity', 03:30:46 INFO - 'tart', 03:30:46 INFO - 'tscrollx', 03:30:46 INFO - 'cart']}, 03:30:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'talos_options': ['--disable-e10s'], 03:30:46 INFO - 'tests': ['tp5o']}, 03:30:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'tests': ['tp5o']}, 03:30:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'talos_options': ['--disable-e10s', 03:30:46 INFO - '--xperf_path', 03:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:46 INFO - 'tests': ['tp5n']}, 03:30:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:46 INFO - 'talos_options': ['--xperf_path', 03:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:46 INFO - 'tests': ['tp5n']}}, 03:30:46 INFO - 'talos.zip': {'path': '', 03:30:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:30:46 INFO - Running post-action listener: _resource_record_post_action 03:30:46 INFO - ##### 03:30:46 INFO - ##### Finished populate-webroot step (success) 03:30:46 INFO - ##### 03:30:46 INFO - ##### 03:30:46 INFO - ##### Running create-virtualenv step. 03:30:46 INFO - ##### 03:30:46 INFO - Running pre-action listener: _resource_record_pre_action 03:30:46 INFO - Running main action method: create_virtualenv 03:30:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:30:46 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 03:30:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:30:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:30:47 INFO - Using real prefix '/usr' 03:30:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:30:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:30:49 INFO - Installing pip.................done. 03:30:49 INFO - Return code: 0 03:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:30:49 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')]} 03:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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')]} 03:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:30:49 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 03:30:49 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 03:30:49 INFO - Using env: {'DISPLAY': ':0', 03:30:49 INFO - 'HOME': '/home/cltbld', 03:30:49 INFO - 'LANG': 'en_US.UTF-8', 03:30:49 INFO - 'LANGUAGE': 'en_US:en', 03:30:49 INFO - 'LOGNAME': 'cltbld', 03:30:49 INFO - 'MAIL': '/var/mail/cltbld', 03:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:49 INFO - 'MOZ_NO_REMOTE': '1', 03:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:49 INFO - 'NO_EM_RESTART': '1', 03:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:49 INFO - 'PWD': '/builds/slave/test', 03:30:49 INFO - 'SHELL': '/bin/bash', 03:30:49 INFO - 'SHLVL': '1', 03:30:49 INFO - 'TERM': 'linux', 03:30:49 INFO - 'TMOUT': '86400', 03:30:49 INFO - 'USER': 'cltbld', 03:30:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:49 INFO - '_': '/tools/buildbot/bin/python'} 03:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:49 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 03:30:49 INFO - Cleaning up... 03:30:49 INFO - Return code: 0 03:30:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:30:49 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')]} 03:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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')]} 03:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:30:49 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 03:30:49 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 03:30:49 INFO - Using env: {'DISPLAY': ':0', 03:30:49 INFO - 'HOME': '/home/cltbld', 03:30:49 INFO - 'LANG': 'en_US.UTF-8', 03:30:49 INFO - 'LANGUAGE': 'en_US:en', 03:30:49 INFO - 'LOGNAME': 'cltbld', 03:30:49 INFO - 'MAIL': '/var/mail/cltbld', 03:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:49 INFO - 'MOZ_NO_REMOTE': '1', 03:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:49 INFO - 'NO_EM_RESTART': '1', 03:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:49 INFO - 'PWD': '/builds/slave/test', 03:30:49 INFO - 'SHELL': '/bin/bash', 03:30:49 INFO - 'SHLVL': '1', 03:30:49 INFO - 'TERM': 'linux', 03:30:49 INFO - 'TMOUT': '86400', 03:30:49 INFO - 'USER': 'cltbld', 03:30:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:49 INFO - '_': '/tools/buildbot/bin/python'} 03:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:49 INFO - Downloading/unpacking psutil>=3.1.1 03:30:49 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 03:30:49 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 03:30:49 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 03:30:49 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 03:30:49 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 03:30:49 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 03:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:51 INFO - Installing collected packages: psutil 03:30:51 INFO - Running setup.py install for psutil 03:30:52 INFO - building 'psutil._psutil_linux' extension 03:30:52 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 03:30:52 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 03:30:52 INFO - building 'psutil._psutil_posix' extension 03:30:52 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 03:30:52 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 03:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:52 INFO - Successfully installed psutil 03:30:52 INFO - Cleaning up... 03:30:52 INFO - Return code: 0 03:30:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:30:52 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')]} 03:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:52 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')]} 03:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:52 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:30:52 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 03:30:52 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 03:30:52 INFO - Using env: {'DISPLAY': ':0', 03:30:52 INFO - 'HOME': '/home/cltbld', 03:30:52 INFO - 'LANG': 'en_US.UTF-8', 03:30:52 INFO - 'LANGUAGE': 'en_US:en', 03:30:52 INFO - 'LOGNAME': 'cltbld', 03:30:52 INFO - 'MAIL': '/var/mail/cltbld', 03:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:52 INFO - 'MOZ_NO_REMOTE': '1', 03:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:52 INFO - 'NO_EM_RESTART': '1', 03:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:52 INFO - 'PWD': '/builds/slave/test', 03:30:52 INFO - 'SHELL': '/bin/bash', 03:30:52 INFO - 'SHLVL': '1', 03:30:52 INFO - 'TERM': 'linux', 03:30:52 INFO - 'TMOUT': '86400', 03:30:52 INFO - 'USER': 'cltbld', 03:30:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:52 INFO - '_': '/tools/buildbot/bin/python'} 03:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:30:52 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 03:30:52 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 03:30:52 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 03:30:52 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 03:30:52 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 03:30:52 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 03:30:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:30:54 INFO - Installing collected packages: mozsystemmonitor 03:30:54 INFO - Running setup.py install for mozsystemmonitor 03:30:54 INFO - Successfully installed mozsystemmonitor 03:30:54 INFO - Cleaning up... 03:30:54 INFO - Return code: 0 03:30:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:30:54 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')]} 03:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:54 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')]} 03:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:54 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:30:54 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 03:30:54 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 03:30:54 INFO - Using env: {'DISPLAY': ':0', 03:30:54 INFO - 'HOME': '/home/cltbld', 03:30:54 INFO - 'LANG': 'en_US.UTF-8', 03:30:54 INFO - 'LANGUAGE': 'en_US:en', 03:30:54 INFO - 'LOGNAME': 'cltbld', 03:30:54 INFO - 'MAIL': '/var/mail/cltbld', 03:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:54 INFO - 'MOZ_NO_REMOTE': '1', 03:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:54 INFO - 'NO_EM_RESTART': '1', 03:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:54 INFO - 'PWD': '/builds/slave/test', 03:30:54 INFO - 'SHELL': '/bin/bash', 03:30:54 INFO - 'SHLVL': '1', 03:30:54 INFO - 'TERM': 'linux', 03:30:54 INFO - 'TMOUT': '86400', 03:30:54 INFO - 'USER': 'cltbld', 03:30:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:54 INFO - '_': '/tools/buildbot/bin/python'} 03:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:30:55 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 03:30:55 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 03:30:55 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 03:30:55 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 03:30:55 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 03:30:55 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 03:30:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:30:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:30:57 INFO - warning: no files found matching '*.txt' 03:30:57 INFO - no previously-included directories found matching 'build' 03:30:57 INFO - no previously-included directories found matching 'dist' 03:30:57 INFO - no previously-included directories found matching '.git*' 03:30:57 INFO - Installing collected packages: jsonschema, functools32 03:30:57 INFO - Running setup.py install for functools32 03:30:57 INFO - warning: no files found matching '*.txt' 03:30:57 INFO - no previously-included directories found matching 'build' 03:30:57 INFO - no previously-included directories found matching 'dist' 03:30:57 INFO - no previously-included directories found matching '.git*' 03:30:57 INFO - Successfully installed jsonschema functools32 03:30:57 INFO - Cleaning up... 03:30:57 INFO - Return code: 0 03:30:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:30:57 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')]} 03:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:57 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')]} 03:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:57 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:30:57 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 03:30:57 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 03:30:57 INFO - Using env: {'DISPLAY': ':0', 03:30:57 INFO - 'HOME': '/home/cltbld', 03:30:57 INFO - 'LANG': 'en_US.UTF-8', 03:30:57 INFO - 'LANGUAGE': 'en_US:en', 03:30:57 INFO - 'LOGNAME': 'cltbld', 03:30:57 INFO - 'MAIL': '/var/mail/cltbld', 03:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:57 INFO - 'MOZ_NO_REMOTE': '1', 03:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:57 INFO - 'NO_EM_RESTART': '1', 03:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:57 INFO - 'PWD': '/builds/slave/test', 03:30:57 INFO - 'SHELL': '/bin/bash', 03:30:57 INFO - 'SHLVL': '1', 03:30:57 INFO - 'TERM': 'linux', 03:30:57 INFO - 'TMOUT': '86400', 03:30:57 INFO - 'USER': 'cltbld', 03:30:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:57 INFO - '_': '/tools/buildbot/bin/python'} 03:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:30:57 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 03:30:57 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 03:30:57 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 03:30:57 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 03:30:57 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 03:30:57 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 03:30:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:30:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31:00 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 03:31:00 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 03:31:00 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 03:31:00 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 03:31:00 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 03:31:00 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 03:31:00 INFO - Downloading docopt-0.6.1.tar.gz 03:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:00 INFO - Running setup.py install for blobuploader 03:31:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:00 INFO - Running setup.py install for requests 03:31:00 INFO - Running setup.py install for docopt 03:31:00 INFO - Successfully installed blobuploader requests docopt 03:31:00 INFO - Cleaning up... 03:31:00 INFO - Return code: 0 03:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:00 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:31:00 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 03:31:00 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 03:31:00 INFO - Using env: {'DISPLAY': ':0', 03:31:00 INFO - 'HOME': '/home/cltbld', 03:31:00 INFO - 'LANG': 'en_US.UTF-8', 03:31:00 INFO - 'LANGUAGE': 'en_US:en', 03:31:00 INFO - 'LOGNAME': 'cltbld', 03:31:00 INFO - 'MAIL': '/var/mail/cltbld', 03:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:00 INFO - 'MOZ_NO_REMOTE': '1', 03:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:00 INFO - 'NO_EM_RESTART': '1', 03:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:00 INFO - 'PWD': '/builds/slave/test', 03:31:00 INFO - 'SHELL': '/bin/bash', 03:31:00 INFO - 'SHLVL': '1', 03:31:00 INFO - 'TERM': 'linux', 03:31:00 INFO - 'TMOUT': '86400', 03:31:00 INFO - 'USER': 'cltbld', 03:31:00 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:00 INFO - '_': '/tools/buildbot/bin/python'} 03:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:01 INFO - Running setup.py (path:/tmp/pip-W7VOhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:01 INFO - Running setup.py (path:/tmp/pip-AILVEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:01 INFO - Running setup.py (path:/tmp/pip-CpGYBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:01 INFO - Running setup.py (path:/tmp/pip-u5BCQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:01 INFO - Running setup.py (path:/tmp/pip-60DOEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:01 INFO - Running setup.py (path:/tmp/pip-tPGgwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:01 INFO - Running setup.py (path:/tmp/pip-QfQ1cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:01 INFO - Running setup.py (path:/tmp/pip-yAU6Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:01 INFO - Running setup.py (path:/tmp/pip-BqIQx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:01 INFO - Running setup.py (path:/tmp/pip-Z6Oan0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:01 INFO - Running setup.py (path:/tmp/pip-hAWQt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:01 INFO - Running setup.py (path:/tmp/pip-l5BeRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:01 INFO - Running setup.py (path:/tmp/pip-MGv_HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:02 INFO - Running setup.py (path:/tmp/pip-hmGJLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:02 INFO - Running setup.py (path:/tmp/pip-cDTXps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:02 INFO - Running setup.py (path:/tmp/pip-JhZDuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:02 INFO - Running setup.py (path:/tmp/pip-ffVFHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:02 INFO - Running setup.py install for manifestparser 03:31:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:02 INFO - Running setup.py install for mozcrash 03:31:02 INFO - Running setup.py install for mozdebug 03:31:02 INFO - Running setup.py install for mozdevice 03:31:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:02 INFO - Running setup.py install for mozfile 03:31:02 INFO - Running setup.py install for mozhttpd 03:31:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:02 INFO - Running setup.py install for mozinfo 03:31:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for mozInstall 03:31:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for mozleak 03:31:03 INFO - Running setup.py install for mozlog 03:31:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for moznetwork 03:31:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for mozprocess 03:31:03 INFO - Running setup.py install for mozprofile 03:31:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for mozrunner 03:31:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:03 INFO - Running setup.py install for mozscreenshot 03:31:03 INFO - Running setup.py install for moztest 03:31:04 INFO - Running setup.py install for mozversion 03:31:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:04 INFO - Cleaning up... 03:31:04 INFO - Return code: 0 03:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:04 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')]} 03:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:04 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')]} 03:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:04 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:31:04 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 03:31:04 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 03:31:04 INFO - Using env: {'DISPLAY': ':0', 03:31:04 INFO - 'HOME': '/home/cltbld', 03:31:04 INFO - 'LANG': 'en_US.UTF-8', 03:31:04 INFO - 'LANGUAGE': 'en_US:en', 03:31:04 INFO - 'LOGNAME': 'cltbld', 03:31:04 INFO - 'MAIL': '/var/mail/cltbld', 03:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:04 INFO - 'MOZ_NO_REMOTE': '1', 03:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:04 INFO - 'NO_EM_RESTART': '1', 03:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:04 INFO - 'PWD': '/builds/slave/test', 03:31:04 INFO - 'SHELL': '/bin/bash', 03:31:04 INFO - 'SHLVL': '1', 03:31:04 INFO - 'TERM': 'linux', 03:31:04 INFO - 'TMOUT': '86400', 03:31:04 INFO - 'USER': 'cltbld', 03:31:04 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:04 INFO - '_': '/tools/buildbot/bin/python'} 03:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:04 INFO - Running setup.py (path:/tmp/pip-1a4l8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:04 INFO - Running setup.py (path:/tmp/pip-m1X9B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:04 INFO - Running setup.py (path:/tmp/pip-XzNi1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:04 INFO - Running setup.py (path:/tmp/pip-fDAODi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:04 INFO - Running setup.py (path:/tmp/pip-cLhUYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:04 INFO - Running setup.py (path:/tmp/pip-7Fjook-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:04 INFO - Running setup.py (path:/tmp/pip-nuZnRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:04 INFO - Running setup.py (path:/tmp/pip-YbjhbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:04 INFO - Running setup.py (path:/tmp/pip-ihMBKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:04 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)) 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:04 INFO - Running setup.py (path:/tmp/pip-Kb3IoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:05 INFO - Running setup.py (path:/tmp/pip-oPWMJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:05 INFO - Running setup.py (path:/tmp/pip-ukHzyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:05 INFO - Running setup.py (path:/tmp/pip-tvoljk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:05 INFO - Running setup.py (path:/tmp/pip-asdOxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:05 INFO - Running setup.py (path:/tmp/pip-kpJMzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:05 INFO - Running setup.py (path:/tmp/pip-ZUH1bK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:05 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)) 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:05 INFO - Running setup.py (path:/tmp/pip-CUog8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:05 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)) 03:31:05 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)) 03:31:05 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)) 03:31:05 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)) 03:31:05 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)) 03:31:05 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)) 03:31:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:05 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 03:31:05 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 03:31:05 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 03:31:05 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 03:31:05 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 03:31:05 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 03:31:07 INFO - Downloading blessings-1.6.tar.gz 03:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:07 INFO - Installing collected packages: blessings 03:31:07 INFO - Running setup.py install for blessings 03:31:07 INFO - Successfully installed blessings 03:31:07 INFO - Cleaning up... 03:31:07 INFO - Return code: 0 03:31:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:07 INFO - Reading from file tmpfile_stdout 03:31:07 INFO - Current package versions: 03:31:07 INFO - argparse == 1.2.1 03:31:07 INFO - blessings == 1.6 03:31:07 INFO - blobuploader == 1.2.4 03:31:07 INFO - docopt == 0.6.1 03:31:07 INFO - functools32 == 3.2.3-2 03:31:07 INFO - jsonschema == 2.5.1 03:31:07 INFO - manifestparser == 1.1 03:31:07 INFO - mozInstall == 1.12 03:31:07 INFO - mozcrash == 0.17 03:31:07 INFO - mozdebug == 0.1 03:31:07 INFO - mozdevice == 0.48 03:31:07 INFO - mozfile == 1.2 03:31:07 INFO - mozhttpd == 0.7 03:31:07 INFO - mozinfo == 0.9 03:31:07 INFO - mozleak == 0.1 03:31:07 INFO - mozlog == 3.2 03:31:07 INFO - moznetwork == 0.27 03:31:07 INFO - mozprocess == 0.22 03:31:07 INFO - mozprofile == 0.28 03:31:07 INFO - mozrunner == 6.11 03:31:07 INFO - mozscreenshot == 0.1 03:31:07 INFO - mozsystemmonitor == 0.3 03:31:07 INFO - moztest == 0.7 03:31:07 INFO - mozversion == 1.4 03:31:07 INFO - psutil == 3.1.1 03:31:07 INFO - requests == 1.2.3 03:31:07 INFO - wsgiref == 0.1.2 03:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:07 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')]} 03:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:07 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')]} 03:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:07 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:31:07 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 03:31:07 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 03:31:07 INFO - Using env: {'DISPLAY': ':0', 03:31:07 INFO - 'HOME': '/home/cltbld', 03:31:07 INFO - 'LANG': 'en_US.UTF-8', 03:31:07 INFO - 'LANGUAGE': 'en_US:en', 03:31:07 INFO - 'LOGNAME': 'cltbld', 03:31:07 INFO - 'MAIL': '/var/mail/cltbld', 03:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:07 INFO - 'MOZ_NO_REMOTE': '1', 03:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:07 INFO - 'NO_EM_RESTART': '1', 03:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:07 INFO - 'PWD': '/builds/slave/test', 03:31:07 INFO - 'SHELL': '/bin/bash', 03:31:07 INFO - 'SHLVL': '1', 03:31:07 INFO - 'TERM': 'linux', 03:31:07 INFO - 'TMOUT': '86400', 03:31:07 INFO - 'USER': 'cltbld', 03:31:07 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:07 INFO - '_': '/tools/buildbot/bin/python'} 03:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 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)) 03:31:08 INFO - Cleaning up... 03:31:08 INFO - Return code: 0 03:31:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:08 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:31:08 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 03:31:08 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 03:31:08 INFO - Using env: {'DISPLAY': ':0', 03:31:08 INFO - 'HOME': '/home/cltbld', 03:31:08 INFO - 'LANG': 'en_US.UTF-8', 03:31:08 INFO - 'LANGUAGE': 'en_US:en', 03:31:08 INFO - 'LOGNAME': 'cltbld', 03:31:08 INFO - 'MAIL': '/var/mail/cltbld', 03:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:08 INFO - 'MOZ_NO_REMOTE': '1', 03:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:08 INFO - 'NO_EM_RESTART': '1', 03:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:08 INFO - 'PWD': '/builds/slave/test', 03:31:08 INFO - 'SHELL': '/bin/bash', 03:31:08 INFO - 'SHLVL': '1', 03:31:08 INFO - 'TERM': 'linux', 03:31:08 INFO - 'TMOUT': '86400', 03:31:08 INFO - 'USER': 'cltbld', 03:31:08 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:08 INFO - '_': '/tools/buildbot/bin/python'} 03:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:31:08 INFO - Cleaning up... 03:31:08 INFO - Return code: 0 03:31:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:08 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 0x7f5cdcf95f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, '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': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', '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 03:31:08 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 03:31:08 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 03:31:08 INFO - Using env: {'DISPLAY': ':0', 03:31:08 INFO - 'HOME': '/home/cltbld', 03:31:08 INFO - 'LANG': 'en_US.UTF-8', 03:31:08 INFO - 'LANGUAGE': 'en_US:en', 03:31:08 INFO - 'LOGNAME': 'cltbld', 03:31:08 INFO - 'MAIL': '/var/mail/cltbld', 03:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:08 INFO - 'MOZ_NO_REMOTE': '1', 03:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:08 INFO - 'NO_EM_RESTART': '1', 03:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:08 INFO - 'PWD': '/builds/slave/test', 03:31:08 INFO - 'SHELL': '/bin/bash', 03:31:08 INFO - 'SHLVL': '1', 03:31:08 INFO - 'TERM': 'linux', 03:31:08 INFO - 'TMOUT': '86400', 03:31:08 INFO - 'USER': 'cltbld', 03:31:08 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:08 INFO - '_': '/tools/buildbot/bin/python'} 03:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:08 INFO - Downloading/unpacking flake8 03:31:08 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 03:31:08 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 03:31:08 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 03:31:08 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 03:31:08 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 03:31:08 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 03:31:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:31:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:31:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:10 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 03:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:11 INFO - no previously-included directories found matching 'docs/_build' 03:31:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:31:11 INFO - Running setup.py install for pep8 03:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:11 INFO - no previously-included directories found matching 'docs/_build' 03:31:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:31:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:31:11 INFO - Cleaning up... 03:31:11 INFO - Return code: 0 03:31:11 INFO - Running post-action listener: _resource_record_post_action 03:31:11 INFO - Running post-action listener: _start_resource_monitoring 03:31:11 INFO - Starting resource monitoring. 03:31:11 INFO - ##### 03:31:11 INFO - ##### Finished create-virtualenv step (success) 03:31:11 INFO - ##### 03:31:11 INFO - ##### 03:31:11 INFO - ##### Running install step. 03:31:11 INFO - ##### 03:31:11 INFO - Running pre-action listener: _resource_record_pre_action 03:31:11 INFO - Running main action method: install 03:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:11 INFO - Reading from file tmpfile_stdout 03:31:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:11 INFO - Reading from file tmpfile_stdout 03:31:11 INFO - Output received: 03:31:11 INFO - Usage: mozinstall [options] installer 03:31:11 INFO - Options: 03:31:11 INFO - -h, --help show this help message and exit 03:31:11 INFO - -d DEST, --destination=DEST 03:31:11 INFO - Directory to install application into. [default: 03:31:11 INFO - "/builds/slave/test"] 03:31:11 INFO - --app=APP Application being installed. [default: firefox] 03:31:11 INFO - mkdir: /builds/slave/test/build/application 03:31:11 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'] 03:31:11 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 03:31:27 INFO - Reading from file tmpfile_stdout 03:31:27 INFO - Output received: 03:31:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:31:27 INFO - Running post-action listener: _resource_record_post_action 03:31:27 INFO - ##### 03:31:27 INFO - ##### Finished install step (success) 03:31:27 INFO - ##### 03:31:27 INFO - ##### 03:31:27 INFO - ##### Running run-tests step. 03:31:27 INFO - ##### 03:31:27 INFO - Running pre-action listener: _resource_record_pre_action 03:31:27 INFO - Running main action method: run_tests 03:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:27 INFO - Python 2.7.3 03:31:27 INFO - Return code: 0 03:31:27 INFO - grabbing minidump binary from tooltool 03:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:27 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 0x26da1c0>, '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 0x26db0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26db550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:27 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 03:31:27 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 03:31:27 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 03:31:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:27 INFO - Return code: 0 03:31:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:31:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:31:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:31:28 INFO - Return code: 0 03:31:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:31:28 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:31:28 INFO - Using env: {'DISPLAY': ':0', 03:31:28 INFO - 'HOME': '/home/cltbld', 03:31:28 INFO - 'LANG': 'en_US.UTF-8', 03:31:28 INFO - 'LANGUAGE': 'en_US:en', 03:31:28 INFO - 'LOGNAME': 'cltbld', 03:31:28 INFO - 'MAIL': '/var/mail/cltbld', 03:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 03:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:28 INFO - 'NO_EM_RESTART': '1', 03:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:28 INFO - 'PWD': '/builds/slave/test', 03:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:28 INFO - 'SHELL': '/bin/bash', 03:31:28 INFO - 'SHLVL': '1', 03:31:28 INFO - 'TERM': 'linux', 03:31:28 INFO - 'TMOUT': '86400', 03:31:28 INFO - 'USER': 'cltbld', 03:31:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - '_': '/tools/buildbot/bin/python'} 03:31:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:31:29 INFO - mozversion application_buildid: 20160627004013 03:31:29 INFO - mozversion application_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 03:31:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:31:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:29 INFO - mozversion application_name: Firefox 03:31:29 INFO - mozversion application_remotingname: firefox-dev 03:31:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:29 INFO - mozversion application_vendor: Mozilla 03:31:29 INFO - mozversion application_version: 49.0a2 03:31:29 INFO - mozversion platform_buildid: 20160627004013 03:31:29 INFO - mozversion platform_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 03:31:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:29 INFO - mozversion platform_version: 49.0a2 03:31:29 INFO - using testdate: 1467023489 03:31:29 INFO - actual date: 1467023489 03:31:29 INFO - starting webserver on 'localhost:51390' 03:31:29 INFO - SUITE-START | Running 2 tests 03:31:29 INFO - TEST-START | dromaeo_css 03:31:29 INFO - operating with platform_type : linux_ 03:31:29 INFO - Initialising browser for dromaeo_css test... 03:31:29 INFO - TEST-INFO | started process 26391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRJROK/profile http://localhost:51390/getInfo.html) 03:31:33 INFO - PROCESS | 26391 | __metrics Screen width/height:1600/1200 03:31:33 INFO - PROCESS | 26391 | colorDepth:24 03:31:33 INFO - PROCESS | 26391 | Browser inner width/height: 1024/697 03:31:33 INFO - PROCESS | 26391 | __metrics 03:31:34 ERROR - PROCESS | 26391 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:31:34 INFO - TEST-INFO | 26391: exit 0 03:31:34 INFO - Browser initialized. 03:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:34 INFO - TEST-INFO | started process 26521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRJROK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:46 INFO - PROCESS | 26521 | RSS: Main: 156119040 03:31:46 INFO - PROCESS | 26521 | 03:32:36 INFO - PROCESS | 26521 | 1467023556243 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] 03:34:44 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51390/tests/dromaeo/cssquery-ext.html) 03:34:45 INFO - PROCESS | 26521 | RSS: Main: 160821248 03:34:45 INFO - PROCESS | 26521 | 03:37:47 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-ext.html (next: http://localhost:51390/tests/dromaeo/cssquery-jquery.html) 03:37:48 INFO - PROCESS | 26521 | RSS: Main: 156807168 03:37:48 INFO - PROCESS | 26521 | 03:40:49 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51390/tests/dromaeo/cssquery-mootools.html) 03:40:50 INFO - PROCESS | 26521 | RSS: Main: 157872128 03:40:50 INFO - PROCESS | 26521 | 03:43:52 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51390/tests/dromaeo/cssquery-prototype.html) 03:43:52 INFO - PROCESS | 26521 | RSS: Main: 156225536 03:43:52 INFO - PROCESS | 26521 | 03:46:55 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51390/tests/dromaeo/cssquery-yui.html) 03:46:55 INFO - PROCESS | 26521 | RSS: Main: 158187520 03:46:55 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | Cycle 1(1): loaded http://localhost:51390/tests/dromaeo/cssquery-yui.html (next: http://localhost:51390/tests/dromaeo/cssquery-dojo.html) 03:49:58 INFO - PROCESS | 26521 | RSS: Main: 158547968 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | __start_tp_report 03:49:58 INFO - PROCESS | 26521 | _x_x_mozilla_page_load 03:49:58 INFO - PROCESS | 26521 | _x_x_mozilla_page_load_details 03:49:58 INFO - PROCESS | 26521 | |i|pagename|runs| 03:49:58 INFO - PROCESS | 26521 | |0;dojo.html;3376.6623337666238;3426.537417448647;3466.6533346665337;3469.705075068619;3475.131217195701;5687.9715601421985;5801.0718285074445;5803.477687008169;5862.179294898718;5868.090445980869;5808.448197421239;5840.678762668052;5887.735051922663;5905.438983296591;5910.911336329958;5221.981713565856;5273.209018647195;5347.679139251657;5367.812126575571;5384.326959130109;5118.9744051279695;5229.555487783524;5394.056040192951;5403.243545903577;5412.269343638598;5264.315638966948;5268.0517506848755;5301.655392399482;5317.12267475866;5376.1398176291805;5378;5388.43421440749;5394.8921021579345;5394.91907621386;5406.107992181324;4986.725730084844;5152.92270615941;5219.634625576211;5289.814856480005;5327.760250788724;5650.773969041234;5691.9146212806845;5701.600887937846;5759.769609215627;5787.102999035156;6101.389861013863;6134.693265336716;6159.38406159385;6179.19670442844;6182.443580077815;5915.763369465216;6129.022607377584;6154.784582539634;6194.628322300677;6215.533834962396;5888;5895.380984996556;5900.291964964232;5909.261342332208;5922.2597175353085;6447.129637498878;6449.322821103717;6465.967670161619;6484;6491.188601424822;5868.853278667982;6023.909641355383;6055.031195008777;6068.605540639845;6165.260168779775;6167.629942203482;6198.566100372931;6207.379262073757;6214.9689251553455;6217.751289948442;6099.786507472217;6126.877462450815;6169.691515424211;6171.814845554641;6176.969115154486;5980.073088671263;6013.789517366871;6033.276006879202;6056.121862329937;6113.96943015291;4668.836590719308;4670.929936050894;4674.228752255908;4675.4155730533685;4695.718256904528;4105.958940410617;4149.585041495826;4182.2890108681595;4190.078182799779;4217.430646862635;5611.5230205433;5930.28836539618;5953.077273022688;5977.491913187428;6014.9699251504335;6265.71804268809;6279.748810047547;6353.142325786052;6396.648184349905;6402.263739669905;5832.358440571534;6099.573029887954;6103.389661033861;6112.480439162632;6131.724072416752;5220.504052115043;5273.1299335609965;5303.708296043677;5313.468653134656;5344.95773324198;6397.296297407282;6430.871382572323;6453.612783233021;6481.351864813481;6482.67586620667;5997.850053748517;6146.907796383147;6251.093591429218;6255.124282600531;6276.058591211355;6421.486281097595;6552.410283074546;6580.374864387875;6585.769498067544;6590.80227593173;6169.938300616936;6242.063690446469;6249.187605611241;6250.0937364082865;6272.717727702173;6060.363661815622;6140.232470941132;6206.379362063757;6372.394622510854;6379.170707807955;6015.398460154124;6045.395460453919;6047.0929360596265;6065.2418447694035;6074.544409169242;5962.165296858357;6010.278766548042;6024.036154215304;6037.577369584263;6051.909221361595;5634;5765.077587585966;5770.3652598215;5776.364599893925;5909.2908850938165;5977.133315669291;5991.161237426851;6000.609960352563;6030.246219222598;6045.78839740607;5886.323072846506;5888.3228428731245;5905.527557795452;5910.970445147746;5927.703614819328 03:49:58 INFO - PROCESS | 26521 | |1;ext.html;587.905935050392;640.57081755224;642.9132067170931;643.1574637225235;651.7425616881332;46429.76785116074;47857.282140767915;48016.759916200455;48107.278390824125;51662.225066623934;42582.36126458106;42848;43241.35137972925;43295.134097318034;43374.349384759174;9192.218661413788;9206.49364284964;9326.067393260688;9344.53277336134;9359.766005849875;9087.182153606174;9184.816303673922;9192.678256261031;9223.077692230756;9230.353875228737;9180.449173049605;9200.171984521392;9219.308551858603;9260.536973151315;9278.953605231964;9180.449173049605;9208.723738287828;9243.983161852191;9253.352265341362;9262.02748711389;9043.27653787702;9119.361644684876;9127.406718563272;9132.08679132088;9158.450492970444;45149.322760158626;45464.77267613674;45491.772541137085;45552.3167152493;45842.77078614619;3590.6409359063887;3694.8522059117604;3713.053171441265;3735.477033215352;3840.558335791392;34152.31695366104;34313;34822.47766283508;35533.82233088818;36357.27285454276;4617.145828021827;4716.693414928019;4743.241081426955;4759.381280433556;4862.294967229732;353168;353326;354248;354345;355340;35145.297094057976;35777;65931;67000.6649966747;67025.32974670191;40864;40952.38571421431;40974;41055.178896422505;41134.38298425526;17267.309307627806;17301.221445034753;17365;17382.65234695299;17435.302587896596;14442.927785361006;14512.492062777752;14602.707945841235;14631.487897923522;14637.85362146365;9251.444913305224;9270.582823772946;9304.581293841793;9351.392159509745;9399.718008459757;3466.9306613868084;3544.0962554548423;3607.0801945503727;3616.3726323154688;3630.981845090758;1112.5939032253332;1131.462555286249;1141.6061458796703;1167.4629670351565;1240.9813852792215;33861.661383386345;34200.31599368049;34364.82817585946;34432.82783586066;34506.206690263825;1138.4990604134155;1192.6958625550603;1234.234774439835;1290.6966862787237;1302.0169771822402;17556.824431755518;17629;17785.377511787025;17913.37303194408;17996.640067198583;582043;889609;898462;905039;912238;66012.33987660061;66379;67069.32930670728;67193.3280667187;67203.66398168076;1501.8197816262118;1511.939522419113;1514.3261248744423;1528.5567185516297;1529.1054732981108;33757.15607109842;34271.657283426845;34732.47901281533;35092.473612896334;35099;57199.71400143139;57403;57726.4227357721;57893.13160302515;57928;533652;539357;540299;542822;544198;31878.840605796824;31963;31971.84014080008;32043;32148.83925580357;53014;53314.73342633262;53549;53894.46105538894;54441.45558544523;44498.93134650429;44615.55384446114;45179.77410112928;45349.54650453453;46452.30321545112;44363.11273774571;44542.3318650227;44802;45504.31743523916;45847.31229031501;43525.12949740924;44293.778531107135;44377.33433998557;44835.77582112199;44853.77573112114;39147.41278880762;39297.60702392939;39377;39602;39819.20361592694;29748.851255743582;29770.553441698827;30022.099337019925;30196.698033020268;30234.697653023188 03:49:58 INFO - PROCESS | 26521 | |2;jquery.html;429.7056516286342;441.8232706917233;443.348278031294;444.1849206705695;445.5544455544456;18872.245110195592;20052.498687532818;20571.28000519982;21196.788032119675;21218;19375;19794.90102549485;20343.389698309038;20383.28658496953;20447;5786.045302525089;5964.598319394939;6017.428344307305;6035.245594300713;6042;5594.524465420444;5829.8251052468495;5896.498797602209;5907.793227237047;5989.6406215626985;5832.795852145176;5937.168796368512;5955.374685658008;5976.073708575178;6641.634710090943;6598.208215014181;6622.2715501294815;6626.370494802986;6660.766873159416;6692.93307066928;5125.359330083739;5428.728563571805;5449.3460784706085;5532.28080349552;5949.613275137102;15183.316750746242;15341.46304879335;22957.426064348358;24694;24936.251912442473;2311.768823117675;2430.5503481855912;2461.0770960889668;2497.887595058227;2520.8361456505454;10862.45687715611;11032.889671103347;11260;11266.943665281704;11419.200655954086;2075.2632815350635;2085.384811480617;2119.6078725435846;2122.3208573256566;2166.9783302166934;76360.61819690921;79195.20804791994;80623.99999999942;81597.36805263862;91701.0829891706;11537.769244615061;11801.763964720829;11861.46623401949;11936.940315298369;12003.459844307026;16928.49214523566;17006.57483562921;17073.31706731717;17092.2308496118;17186.828131718772;8664.610092545725;8684.131586841266;8833.60248788806;8953.194212520773;8979.910200897908;8276.710315138942;8305.501669899704;8458.87311690325;8471.534065626449;8520.8721869172;5142.61430392722;5167.457416971164;5220.190870415092;5236.1098613235845;5292.92060619091;2114.661654135331;2132.4455641533314;2141.65733482642;2154.64448366021;2176.912923483043;940.4075432477632;999.2705325112563;1064.9094826939643;1077.0414331245197;1138.6925530106826;19682.21271149138;20201.898990504953;20209.89895050545;20265.189392424432;20391.286304979254;971.9514024298757;1053.7312985188728;1063.0963680871198;1074.08702602787;1100.5047728522197;11210;11475;11645.941770291094;11647.475863586158;11764.411779410995;463205;463325;464238;464517;469850;83244;86108.56945715232;101179.49410253049;102341;102942.48528757309;1043.186314674555;1048.9370637761758;1059.7562560611102;1089.6349722842779;1101.2236373356764;18337.358192463198;18427.815721843148;18462.26150953947;18491.537711557317;18535.53661158482;44872.77563612161;45366.31950520679;45463.09073818571;46422.53577464317;47508.2873756887;64708.0293795584;65225.347746523825;65321;65970.67014665088;66745;8980.955095224483;8996.145366190201;9127.589258483516;9200.493972831558;9330;7776.883346749916;13015.739685206432;13277.47309059432;13920.791188132389;14041.789373159616;12361.876381236432;12392.690182745504;12434.129610927148;12475.251484910934;12782;12567.434465448894;12612.810807837706;12615.432305546456;12710.872891270968;12996.285204313854;12670.429830657828;12684.746305073662;13047.608571742863;13171.384889030869;13230.867691322963;9460.668876589287;9595.904040959502;12751.872481275068;13094.14888032275;13261.734765304833;11753.236039657395;13096.476140954635;13540.661483462598;13680.179189248676;13690.86309136896 03:49:58 INFO - PROCESS | 26521 | |3;mootools.html;1554.0520282627594;1592.1879841280945;1790.8925464472134;1867.9906600466995;1913.445100920733;8028.638711257993;8595.957020214899;8916.420432671872;9111.58997845097;9139.268858491321;8900.643974241038;9129.68046118386;9139.954300228506;9151.77120571986;9206.907930920688;2449.0326321103153;2464.2607217834666;2469.086438017936;2469.604863221885;2480.5659009673327;2404.3748625357443;2479.752024797524;2491.165459571046;2501.462185630089;2503.5869081601513;2461.618449140386;2468.456939473322;2514.53481105996;2518.0431436054273;2522.3567990162483;2417.359399759065;2437.4150203951103;2441.6581678565017;2444.278937713379;2456.7788898999174;2403.783659470656;2411.397150712322;2420.322309753272;2420.7095148582107;2466.062896099471;9199.58601862911;9257.259419246444;9267.629294828199;9276.675316363895;9286.860697089549;5654.943450565483;5788.710564471801;5798.681072541009;5799.130130480419;5810.5932584719085;3445.448728203475;3508.719302455823;3589.5513060867393;3607.134287770942;3664.395374763161;1314.3147739447716;1339.7521458530202;1348.6763176837587;2284.7486776454575;2375.904963801463;12821.423035963404;12901.419436125398;12966.286854223014;13050.86949130506;13085.542006029744;4797.232442809134;4798.376211092535;4887.218045112836;4911;4938.308636790852;10076.798464030679;10134.442605656759;10144.644937427302;10175.389476631426;10216.642417515352;5630.436956304418;5635.915461268084;5653.773849046074;5655.0386434306265;5659.037963546125;4405.757683327383;5112.693238405707;5201.271821944931;5249.501297376743;5285.07511185541;3790.450384694204;3911.4523966644692;3918.9608103919168;3943.5662077171487;3987.481627388479;1210.2254557083475;1222.9143959922899;1238.8699186585459;1249.8187762774528;1264.5826877130526;1008.6368907193405;1043.2592859069994;1049.7113293844102;1056.9471526423647;1061.639042725477;6294.307626161119;6379.585326953733;6386.84032899172;6448.226212854487;6456.451201647913;478.6410192355733;480.5530856303671;482.94929032451086;496.1515807968415;496.73424717775816;4235.682323825726;4241.63946064588;4282.164977829356;4329.393884856123;4407.184670835905;11329.60346387889;11665.591704290478;11698.47356868926;11701.707457313465;11724.120690948213;11585.246958947642;11604.6518604442;11627.88372116268;11639.41802909868;11664.766704666028;8129.4715843469985;8382.203690649378;8388.245057944692;8536.402451828437;8795.296376289882;6393.904091438725;6647.202335719745;6676.76631317921;6779.6271205084195;6780.660966951534;10491.265611407434;10632.25574209798;10835;10860.131189504662;10987.670369888916;10770.569177232823;10971.286866353661;10980.231383803057;10994.505247263733;11008.779824403628;7302.123745150616;8060.11338752725;8313.750587482386;8378.622961966848;8433.072362040048;9193.448393096436;9235.630574776933;9305.255579553484;9339.066093390607;9348.859767103635;8009.8398032040195;8156.265936065783;8160.347172226089;8200.507969521848;8284.792880178044;8128.674853005814;8139.430239883388;8221.83556328882;8245.670173193004;8282.047564530156;7509.4367922404945;7584.886226706714;7653.464257501922;7682.116556596063;7701.229877012254;5496.285482887199;5539.113741801292;5603.691796951207;5657.6888271143835;5695.572832037531;5626.971865140812;5632.352279487911;5683.5737319700365;5701.771929122789;5888.587798854039 03:49:58 INFO - PROCESS | 26521 | |4;prototype.html;362.79864675105307;365.17288341905606;366.97064234861216;370.3019807662556;370.6238168259217;5689.573282003846;5733.598648094635;5813.8024086752275;5867.6479411235305;5875.823725288237;5640.182173584828;5687.2606561147095;5699.774548472078;5808.41915808419;5810.622309549887;4519.932201016987;4535.841245556406;4542.909141817178;4566.452025756914;4601.953980460186;4505.639548836085;4552.339910712944;4582.1064892346;4586.44962604488;4604.148232576984;4513.165064463068;4519.367288579601;4524.773761311938;4539.114872599845;4559.680822342438;4361.978190109061;4508.2561377372695;4532.818687252522;4536.56902594253;4547.340635607835;3939.527256729182;3940.822662980173;3949.4273330367223;3952.7628342299554;3957.762534247954;5595.608307418483;5627.493525582676;5649.604527683064;5667.773289068432;5704.429557044304;5127.589792816566;5201.323827902386;5229.5293423591675;5233.476652334774;5236.895262094737;6015.699215039274;6045.428188670933;6074.48366888815;6114.327423983358;6116.256866792943;4356.324769660708;4394.1870753910625;4402.185595664812;4423.248047831877;4443.311286750559;6080.7805151119965;6331.936680633181;6382.521310901701;6387.393197646216;6410.634416931101;4427.734335939854;4433.135538570013;4459.130469558405;4485.125400546862;4491.585150677526;4525.389072475239;4537.8411755588395;4566.269396896547;4570.771461426915;4579.450465944108;4958.256261560723;5192.428832828386;5205.947940520622;5212.9739351303;5226.007058658843;5820.24336836217;5931.406859314034;6066.241719785027;6093.2078829751845;6113.602615829956;1532.8160620725362;1537.7232098222428;1550.1396724817835;1555.898866573669;1559.6976524602085;986.2297176568078;988.1748739802346;990.6829814459304;996.5415908682088;1019.6380284998867;5165.295452500666;5225.843224703265;5232;5288.334174734946;5316.3354580677415;5418.485243901823;5546.889062218814;5550.333959924754;5594.608377413542;5601.747921343549;4897.2898929655;5214.582833373321;5327.38735045467;5329.333833270841;5360.463953604608;2915.752161066333;2952;2965.9703402966124;2976.895808646687;3004.2339203502966;5633.915491267634;5694.487496125369;5701.71491425427;5764.942350576524;5798.536117110621;5877.118432235199;6405.487560995202;6405.935940640534;6409.692510696145;6434.806955791334;6588.275289718127;6603.70283337251;6616.96691516549;6635.668216589248;6661.2006559213205;6114.266288045285;6217.564770466205;6254.968725156527;6290.402411770874;6373.904391434039;6165.290991536031;6207.96896015517;6209.316975132822;6221.657808820377;6228.190335256388;6387.4570661492435;6413.743450262124;6488.967555162194;6508.60948343101;6560.770373036921;4462.367203350039;4479.619232365286;4484.68607197493;4489.610344190246;4520.299353600197;6156.969215153896;6282.089097080989;6314.968425157845;6317.915088021034;6325.335839736761;6087.573869829246;6104.565427124533;6106.57253992234;6108.267007959073;6120.449159575482;6034.63792172471;6035.185249991195;6047.941610218282;6079.665618391031;6123.908141377972;5650.23721797552;5930.318013428338;5941.752232031322;5959.139612985705;5987.850303742442;6086.3305036446845;6097.481714054177;6106.2061931948565;6107.511399088151;6156.907646385218;5722.885542289214;5944.821655350346;5978.940210597838;6019.909701354396;6023.337432882292 03:49:58 INFO - PROCESS | 26521 | |5;yui.html;723.2658851265962;777.3392616276166;867.9566021698913;879.9560021998899;890.6481939633845;15931.203439828007;17587.472375828747;17622;17656.735148972777;17659.55851103723;16186.190690465488;16233.756493652605;16283;16317.184140792971;16354;882.5498995512303;883.1521739130443;893.1113542025694;897.344938195118;905.8143080668447;900.5722281916103;904.0552622509493;905.732808821396;905.782612173077;908.2960705453294;944.1219665710886;950.0831697411996;950.9524523773819;953.4041224234853;953.7138858342504;931.4318265857821;933.7292185266265;937.5218638494349;940.8118376324728;943.4622265308777;687.9724811007553;688.3529482286634;688.6900894597451;690.9032735417046;691.4468425259772;15266.694666106727;15343.539693809093;15385.153816540085;15432.459863904709;15463.304151313105;13810.516631917935;13937.372818223204;13940.302984850818;14534.636634084127;14759.114453132739;13336.599902002956;13468.39392227352;13535.052546321762;13558.661033474144;13695.657608559766;4105.4252404663375;4738.786754596052;4760.214564596872;4831.975840120777;4844.612431005511;63530.36469635291;65678.34321656771;65821;76183;78307.21692783057;12106.273623582612;12113.39433028345;12159.939200303941;12222.20555663879;12340.074494412955;22665.320040398816;22920.541589168122;22958.885205573864;22964.540709185723;22990.540189196458;8624.482531048032;8686.956565217133;8779.165979231962;9131.726048218565;9146.070445817317;4755.38180036593;4774.809007639725;4850.320955066293;4955.083309587738;4997.775100120503;3813.923721525554;3937.9803100984886;3945.112349721347;3950.7827069511454;3959.524857017119;659.868026394723;661.8841702702105;666.643345809999;673.0778832998823;680.8434060166189;638.9297177310492;762.8398036412416;788.4756636836554;794.6265255329986;804.3926835239474;10744.247902646895;10754.487677564595;10981.3960232188;10995.780084398268;11138.443077846076;789.553902045354;814.3973459639824;814.5153633588005;823.8928939237861;824.7608193623902;4784.210605250095;4952.306677065142;4984.700917944935;4993.90012199754;5036.370453693288;69270.65364673144;96927.51536242274;98258;99775.50112249392;112952;63701;80645;95316;97888;101028;58293;75901.24098758942;85402.57298713467;90201;92010.53994729984;10127.392356458637;10155.339902906288;10263.538140783534;10503.684889453329;10550.208734345108;37574.624253757116;45253.321200181366;45271;52267.73866130645;52333.953320932604;76310.23689763031;76353.61823190849;77845;79702.6014869922;79827;12771;13424.93287533556;13570.389332479865;13636.727265454438;13843.169409835442;15105.54683359501;15448.613784655474;15512;15516.146611936452;15549.84450155484;13241.3379331035;13606.455741770218;14202.218877961812;14416.639584010483;14669.119852808866;14865.331060102106;15258.771118433011;15373.615659608151;15523.84476155224;15629.218539073228;13640.590782276548;13840;14880;14980.550583482078;15006.2496875158;11198.328100314007;11595.246308989888;11666.416679165837;11796.233244839059;11876.82184767212;11217.158713096387;11883.524659013543;12096.395180240777;12113.818292725793;12166.45250963691 03:49:58 INFO - PROCESS | 26521 | __end_tp_report 03:49:58 INFO - PROCESS | 26521 | __start_cc_report 03:49:58 INFO - PROCESS | 26521 | _x_x_mozilla_cycle_collect,348 03:49:58 INFO - PROCESS | 26521 | __end_cc_report 03:49:58 INFO - PROCESS | 26521 | __startTimestamp1467024598527__endTimestamp 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | ------- Summary: start ------- 03:49:58 INFO - PROCESS | 26521 | Number of tests: 6 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#0] dojo.html Cycles:165 Average:5765.46 Median:5977.13 stddev:658.44 (11.0%) stddev-sans-first:633.23 03:49:58 INFO - PROCESS | 26521 | Values: 3376.7 3426.5 3466.7 3469.7 3475.1 5688.0 5801.1 5803.5 5862.2 5868.1 5808.4 5840.7 5887.7 5905.4 5910.9 5222.0 5273.2 5347.7 5367.8 5384.3 5119.0 5229.6 5394.1 5403.2 5412.3 5264.3 5268.1 5301.7 5317.1 5376.1 5378.0 5388.4 5394.9 5394.9 5406.1 4986.7 5152.9 5219.6 5289.8 5327.8 5650.8 5691.9 5701.6 5759.8 5787.1 6101.4 6134.7 6159.4 6179.2 6182.4 5915.8 6129.0 6154.8 6194.6 6215.5 5888.0 5895.4 5900.3 5909.3 5922.3 6447.1 6449.3 6466.0 6484.0 6491.2 5868.9 6023.9 6055.0 6068.6 6165.3 6167.6 6198.6 6207.4 6215.0 6217.8 6099.8 6126.9 6169.7 6171.8 6177.0 5980.1 6013.8 6033.3 6056.1 6114.0 4668.8 4670.9 4674.2 4675.4 4695.7 4106.0 4149.6 4182.3 4190.1 4217.4 5611.5 5930.3 5953.1 5977.5 6015.0 6265.7 6279.7 6353.1 6396.6 6402.3 5832.4 6099.6 6103.4 6112.5 6131.7 5220.5 5273.1 5303.7 5313.5 5345.0 6397.3 6430.9 6453.6 6481.4 6482.7 5997.9 6146.9 6251.1 6255.1 6276.1 6421.5 6552.4 6580.4 6585.8 6590.8 6169.9 6242.1 6249.2 6250.1 6272.7 6060.4 6140.2 6206.4 6372.4 6379.2 6015.4 6045.4 6047.1 6065.2 6074.5 5962.2 6010.3 6024.0 6037.6 6051.9 5634.0 5765.1 5770.4 5776.4 5909.3 5977.1 5991.2 6000.6 6030.2 6045.8 5886.3 5888.3 5905.5 5911.0 5927.7 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#1] ext.html Cycles:180 Average:72316.66 Median:44438.13 stddev:165690.04 (372.9%) stddev-sans-first:166067.32 03:49:58 INFO - PROCESS | 26521 | Values: 587.9 640.6 642.9 643.2 651.7 46429.8 47857.3 48016.8 48107.3 51662.2 42582.4 42848.0 43241.4 43295.1 43374.3 9192.2 9206.5 9326.1 9344.5 9359.8 9087.2 9184.8 9192.7 9223.1 9230.4 9180.4 9200.2 9219.3 9260.5 9279.0 9180.4 9208.7 9244.0 9253.4 9262.0 9043.3 9119.4 9127.4 9132.1 9158.5 45149.3 45464.8 45491.8 45552.3 45842.8 3590.6 3694.9 3713.1 3735.5 3840.6 34152.3 34313.0 34822.5 35533.8 36357.3 4617.1 4716.7 4743.2 4759.4 4862.3 353168.0 353326.0 354248.0 354345.0 355340.0 35145.3 35777.0 65931.0 67000.7 67025.3 40864.0 40952.4 40974.0 41055.2 41134.4 17267.3 17301.2 17365.0 17382.7 17435.3 14442.9 14512.5 14602.7 14631.5 14637.9 9251.4 9270.6 9304.6 9351.4 9399.7 3466.9 3544.1 3607.1 3616.4 3631.0 1112.6 1131.5 1141.6 1167.5 1241.0 33861.7 34200.3 34364.8 34432.8 34506.2 1138.5 1192.7 1234.2 1290.7 1302.0 17556.8 17629.0 17785.4 17913.4 17996.6 582043.0 889609.0 898462.0 905039.0 912238.0 66012.3 66379.0 67069.3 67193.3 67203.7 1501.8 1511.9 1514.3 1528.6 1529.1 33757.2 34271.7 34732.5 35092.5 35099.0 57199.7 57403.0 57726.4 57893.1 57928.0 533652.0 539357.0 540299.0 542822.0 544198.0 31878.8 31963.0 31971.8 32043.0 32148.8 53014.0 53314.7 53549.0 53894.5 54441.5 44498.9 44615.6 45179.8 45349.5 46452.3 44363.1 44542.3 44802.0 45504.3 45847.3 43525.1 44293.8 44377.3 44835.8 44853.8 39147.4 39297.6 39377.0 39602.0 39819.2 29748.9 29770.6 30022.1 30196.7 30234.7 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#2] jquery.html Cycles:180 Average:29225.60 Median:2148.15 stddev:76935.00 (3581.5%) stddev-sans-first:77120.44 03:49:58 INFO - PROCESS | 26521 | Values: 429.7 441.8 443.3 444.2 445.6 18872.2 20052.5 20571.3 21196.8 21218.0 19375.0 19794.9 20343.4 20383.3 20447.0 5786.0 5964.6 6017.4 6035.2 6042.0 5594.5 5829.8 5896.5 5907.8 5989.6 5832.8 5937.2 5955.4 5976.1 6641.6 6598.2 6622.3 6626.4 6660.8 6692.9 5125.4 5428.7 5449.3 5532.3 5949.6 15183.3 15341.5 22957.4 24694.0 24936.3 2311.8 2430.6 2461.1 2497.9 2520.8 10862.5 11032.9 11260.0 11266.9 11419.2 2075.3 2085.4 2119.6 2122.3 2167.0 76360.6 79195.2 80624.0 81597.4 91701.1 11537.8 11801.8 11861.5 11936.9 12003.5 16928.5 17006.6 17073.3 17092.2 17186.8 8664.6 8684.1 8833.6 8953.2 8979.9 8276.7 8305.5 8458.9 8471.5 8520.9 5142.6 5167.5 5220.2 5236.1 5292.9 2114.7 2132.4 2141.7 2154.6 2176.9 940.4 999.3 1064.9 1077.0 1138.7 19682.2 20201.9 20209.9 20265.2 20391.3 972.0 1053.7 1063.1 1074.1 1100.5 11210.0 11475.0 11645.9 11647.5 11764.4 463205.0 463325.0 464238.0 464517.0 469850.0 83244.0 86108.6 101179.5 102341.0 102942.5 1043.2 1048.9 1059.8 1089.6 1101.2 18337.4 18427.8 18462.3 18491.5 18535.5 44872.8 45366.3 45463.1 46422.5 47508.3 64708.0 65225.3 65321.0 65970.7 66745.0 8981.0 8996.1 9127.6 9200.5 9330.0 7776.9 13015.7 13277.5 13920.8 14041.8 12361.9 12392.7 12434.1 12475.3 12782.0 12567.4 12612.8 12615.4 12710.9 12996.3 12670.4 12684.7 13047.6 13171.4 13230.9 9460.7 9595.9 12751.9 13094.1 13261.7 11753.2 13096.5 13540.7 13680.2 13690.9 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#3] mootools.html Cycles:180 Average:6139.47 Median:2442.91 stddev:3467.94 (142.0%) stddev-sans-first:3460.55 03:49:58 INFO - PROCESS | 26521 | Values: 1554.1 1592.2 1790.9 1868.0 1913.4 8028.6 8596.0 8916.4 9111.6 9139.3 8900.6 9129.7 9140.0 9151.8 9206.9 2449.0 2464.3 2469.1 2469.6 2480.6 2404.4 2479.8 2491.2 2501.5 2503.6 2461.6 2468.5 2514.5 2518.0 2522.4 2417.4 2437.4 2441.7 2444.3 2456.8 2403.8 2411.4 2420.3 2420.7 2466.1 9199.6 9257.3 9267.6 9276.7 9286.9 5654.9 5788.7 5798.7 5799.1 5810.6 3445.4 3508.7 3589.6 3607.1 3664.4 1314.3 1339.8 1348.7 2284.7 2375.9 12821.4 12901.4 12966.3 13050.9 13085.5 4797.2 4798.4 4887.2 4911.0 4938.3 10076.8 10134.4 10144.6 10175.4 10216.6 5630.4 5635.9 5653.8 5655.0 5659.0 4405.8 5112.7 5201.3 5249.5 5285.1 3790.5 3911.5 3919.0 3943.6 3987.5 1210.2 1222.9 1238.9 1249.8 1264.6 1008.6 1043.3 1049.7 1056.9 1061.6 6294.3 6379.6 6386.8 6448.2 6456.5 478.6 480.6 482.9 496.2 496.7 4235.7 4241.6 4282.2 4329.4 4407.2 11329.6 11665.6 11698.5 11701.7 11724.1 11585.2 11604.7 11627.9 11639.4 11664.8 8129.5 8382.2 8388.2 8536.4 8795.3 6393.9 6647.2 6676.8 6779.6 6780.7 10491.3 10632.3 10835.0 10860.1 10987.7 10770.6 10971.3 10980.2 10994.5 11008.8 7302.1 8060.1 8313.8 8378.6 8433.1 9193.4 9235.6 9305.3 9339.1 9348.9 8009.8 8156.3 8160.3 8200.5 8284.8 8128.7 8139.4 8221.8 8245.7 8282.0 7509.4 7584.9 7653.5 7682.1 7701.2 5496.3 5539.1 5603.7 5657.7 5695.6 5627.0 5632.4 5683.6 5701.8 5888.6 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#4] prototype.html Cycles:180 Average:5049.81 Median:5649.92 stddev:1491.56 (26.4%) stddev-sans-first:1453.67 03:49:58 INFO - PROCESS | 26521 | Values: 362.8 365.2 367.0 370.3 370.6 5689.6 5733.6 5813.8 5867.6 5875.8 5640.2 5687.3 5699.8 5808.4 5810.6 4519.9 4535.8 4542.9 4566.5 4602.0 4505.6 4552.3 4582.1 4586.4 4604.1 4513.2 4519.4 4524.8 4539.1 4559.7 4362.0 4508.3 4532.8 4536.6 4547.3 3939.5 3940.8 3949.4 3952.8 3957.8 5595.6 5627.5 5649.6 5667.8 5704.4 5127.6 5201.3 5229.5 5233.5 5236.9 6015.7 6045.4 6074.5 6114.3 6116.3 4356.3 4394.2 4402.2 4423.2 4443.3 6080.8 6331.9 6382.5 6387.4 6410.6 4427.7 4433.1 4459.1 4485.1 4491.6 4525.4 4537.8 4566.3 4570.8 4579.5 4958.3 5192.4 5205.9 5213.0 5226.0 5820.2 5931.4 6066.2 6093.2 6113.6 1532.8 1537.7 1550.1 1555.9 1559.7 986.2 988.2 990.7 996.5 1019.6 5165.3 5225.8 5232.0 5288.3 5316.3 5418.5 5546.9 5550.3 5594.6 5601.7 4897.3 5214.6 5327.4 5329.3 5360.5 2915.8 2952.0 2966.0 2976.9 3004.2 5633.9 5694.5 5701.7 5764.9 5798.5 5877.1 6405.5 6405.9 6409.7 6434.8 6588.3 6603.7 6617.0 6635.7 6661.2 6114.3 6217.6 6255.0 6290.4 6373.9 6165.3 6208.0 6209.3 6221.7 6228.2 6387.5 6413.7 6489.0 6508.6 6560.8 4462.4 4479.6 4484.7 4489.6 4520.3 6157.0 6282.1 6315.0 6317.9 6325.3 6087.6 6104.6 6106.6 6108.3 6120.4 6034.6 6035.2 6047.9 6079.7 6123.9 5650.2 5930.3 5941.8 5959.1 5987.9 6086.3 6097.5 6106.2 6107.5 6156.9 5722.9 5944.8 5978.9 6019.9 6023.3 03:49:58 INFO - PROCESS | 26521 | 03:49:58 INFO - PROCESS | 26521 | [#5] yui.html Cycles:180 Average:20052.55 Median:25004.89 stddev:27018.47 (108.1%) stddev-sans-first:27055.28 03:49:58 INFO - PROCESS | 26521 | Values: 723.3 777.3 868.0 880.0 890.6 15931.2 17587.5 17622.0 17656.7 17659.6 16186.2 16233.8 16283.0 16317.2 16354.0 882.5 883.2 893.1 897.3 905.8 900.6 904.1 905.7 905.8 908.3 944.1 950.1 951.0 953.4 953.7 931.4 933.7 937.5 940.8 943.5 688.0 688.4 688.7 690.9 691.4 15266.7 15343.5 15385.2 15432.5 15463.3 13810.5 13937.4 13940.3 14534.6 14759.1 13336.6 13468.4 13535.1 13558.7 13695.7 4105.4 4738.8 4760.2 4832.0 4844.6 63530.4 65678.3 65821.0 76183.0 78307.2 12106.3 12113.4 12159.9 12222.2 12340.1 22665.3 22920.5 22958.9 22964.5 22990.5 8624.5 8687.0 8779.2 9131.7 9146.1 4755.4 4774.8 4850.3 4955.1 4997.8 3813.9 3938.0 3945.1 3950.8 3959.5 659.9 661.9 666.6 673.1 680.8 638.9 762.8 788.5 794.6 804.4 10744.2 10754.5 10981.4 10995.8 11138.4 789.6 814.4 814.5 823.9 824.8 4784.2 4952.3 4984.7 4993.9 5036.4 69270.7 96927.5 98258.0 99775.5 112952.0 63701.0 80645.0 95316.0 97888.0 101028.0 58293.0 75901.2 85402.6 90201.0 92010.5 10127.4 10155.3 10263.5 10503.7 10550.2 37574.6 45253.3 45271.0 52267.7 52334.0 76310.2 76353.6 77845.0 79702.6 79827.0 12771.0 13424.9 13570.4 13636.7 13843.2 15105.5 15448.6 15512.0 15516.1 15549.8 13241.3 13606.5 14202.2 14416.6 14669.1 14865.3 15258.8 15373.6 15523.8 15629.2 13640.6 13840.0 14880.0 14980.6 15006.2 11198.3 11595.2 11666.4 11796.2 11876.8 11217.2 11883.5 12096.4 12113.8 12166.5 03:49:58 INFO - PROCESS | 26521 | -------- Summary: end -------- 03:49:58 INFO - PROCESS | 26521 | 03:49:59 INFO - TEST-INFO | 26521: exit 0 03:49:59 INFO - TEST-OK | dromaeo_css | took 1110212ms 03:49:59 INFO - TEST-START | kraken 03:49:59 INFO - operating with platform_type : linux_ 03:49:59 INFO - Initialising browser for kraken test... 03:49:59 INFO - TEST-INFO | started process 26764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2AZL9h/profile http://localhost:51390/getInfo.html) 03:50:03 INFO - PROCESS | 26764 | __metrics Screen width/height:1600/1200 03:50:03 INFO - PROCESS | 26764 | colorDepth:24 03:50:03 INFO - PROCESS | 26764 | Browser inner width/height: 1024/697 03:50:03 INFO - PROCESS | 26764 | __metrics 03:50:04 ERROR - PROCESS | 26764 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:50:05 INFO - TEST-INFO | 26764: exit 0 03:50:05 INFO - Browser initialized. 03:50:05 INFO - Running cycle 1/1 for kraken test... 03:50:05 INFO - TEST-INFO | started process 26892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2AZL9h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:16 INFO - PROCESS | 26892 | RSS: Main: 156241920 03:50:16 INFO - PROCESS | 26892 | 03:50:19 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51390/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:20 INFO - PROCESS | 26892 | RSS: Main: 161550336 03:50:20 INFO - PROCESS | 26892 | 03:50:22 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51390/tests/kraken/driver.html?testName=audio-dft) 03:50:23 INFO - PROCESS | 26892 | RSS: Main: 160264192 03:50:23 INFO - PROCESS | 26892 | 03:50:26 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51390/tests/kraken/driver.html?testName=audio-fft) 03:50:26 INFO - PROCESS | 26892 | RSS: Main: 161349632 03:50:26 INFO - PROCESS | 26892 | 03:50:29 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51390/tests/kraken/driver.html?testName=audio-oscillator) 03:50:29 INFO - PROCESS | 26892 | RSS: Main: 160702464 03:50:29 INFO - PROCESS | 26892 | 03:50:31 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51390/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:50:32 INFO - PROCESS | 26892 | RSS: Main: 160739328 03:50:32 INFO - PROCESS | 26892 | 03:50:35 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51390/tests/kraken/driver.html?testName=imaging-darkroom) 03:50:36 INFO - PROCESS | 26892 | RSS: Main: 159948800 03:50:36 INFO - PROCESS | 26892 | 03:50:39 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51390/tests/kraken/driver.html?testName=imaging-desaturate) 03:50:40 INFO - PROCESS | 26892 | RSS: Main: 160739328 03:50:40 INFO - PROCESS | 26892 | 03:50:43 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51390/tests/kraken/driver.html?testName=json-parse-financial) 03:50:43 INFO - PROCESS | 26892 | RSS: Main: 161050624 03:50:43 INFO - PROCESS | 26892 | 03:50:45 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51390/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:50:45 INFO - PROCESS | 26892 | RSS: Main: 161136640 03:50:45 INFO - PROCESS | 26892 | 03:50:47 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:50:48 INFO - PROCESS | 26892 | RSS: Main: 160968704 03:50:48 INFO - PROCESS | 26892 | 03:50:50 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:50:51 INFO - PROCESS | 26892 | RSS: Main: 161595392 03:50:51 INFO - PROCESS | 26892 | 03:50:53 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:50:54 INFO - PROCESS | 26892 | RSS: Main: 161259520 03:50:54 INFO - PROCESS | 26892 | 03:50:56 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:50:57 INFO - PROCESS | 26892 | RSS: Main: 161214464 03:50:57 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | Cycle 1(1): loaded http://localhost:51390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51390/tests/kraken/driver.html?testName=ai-astar) 03:50:59 INFO - PROCESS | 26892 | RSS: Main: 161898496 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | __start_tp_report 03:50:59 INFO - PROCESS | 26892 | _x_x_mozilla_page_load 03:50:59 INFO - PROCESS | 26892 | _x_x_mozilla_page_load_details 03:50:59 INFO - PROCESS | 26892 | |i|pagename|runs| 03:50:59 INFO - PROCESS | 26892 | |0;ai-astar;111;105;110;111;111;112;112;110;112;110 03:50:59 INFO - PROCESS | 26892 | |1;audio-beat-detection;161;132;151;180;126;164;150;126;179;126 03:50:59 INFO - PROCESS | 26892 | |2;audio-dft;162;161;162;162;162;163;162;162;162;163 03:50:59 INFO - PROCESS | 26892 | |3;audio-fft;75;76;76;172;77;75;76;76;161;77 03:50:59 INFO - PROCESS | 26892 | |4;audio-oscillator;85;85;151;85;85;84;134;84;83;149 03:50:59 INFO - PROCESS | 26892 | |5;imaging-gaussian-blur;117;117;117;117;118;118;118;117;118;117 03:50:59 INFO - PROCESS | 26892 | |6;imaging-darkroom;146;148;146;148;147;147;146;147;147;146 03:50:59 INFO - PROCESS | 26892 | |7;imaging-desaturate;95;95;96;96;95;95;96;96;96;96 03:50:59 INFO - PROCESS | 26892 | |8;json-parse-financial;55;54;51;53;52;59;55;52;51;51 03:50:59 INFO - PROCESS | 26892 | |9;json-stringify-tinderbox;60;60;60;60;60;60;61;60;70;60 03:50:59 INFO - PROCESS | 26892 | |10;stanford-crypto-aes;81;79;75;76;77;83;84;77;111;83 03:50:59 INFO - PROCESS | 26892 | |11;stanford-crypto-ccm;139;123;132;121;141;117;118;128;122;125 03:50:59 INFO - PROCESS | 26892 | |12;stanford-crypto-pbkdf2;160;155;160;160;158;164;156;156;157;160 03:50:59 INFO - PROCESS | 26892 | |13;stanford-crypto-sha256-iterative;62;66;61;61;61;63;60;61;61;65 03:50:59 INFO - PROCESS | 26892 | __end_tp_report 03:50:59 INFO - PROCESS | 26892 | __start_cc_report 03:50:59 INFO - PROCESS | 26892 | _x_x_mozilla_cycle_collect,876 03:50:59 INFO - PROCESS | 26892 | __end_cc_report 03:50:59 INFO - PROCESS | 26892 | __startTimestamp1467024659369__endTimestamp 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | ------- Summary: start ------- 03:50:59 INFO - PROCESS | 26892 | Number of tests: 14 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.07 (1.9%) stddev-sans-first:2.18 03:50:59 INFO - PROCESS | 26892 | Values: 111.0 105.0 110.0 111.0 111.0 112.0 112.0 110.0 112.0 110.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:156.00 stddev:21.37 (13.7%) stddev-sans-first:22.25 03:50:59 INFO - PROCESS | 26892 | Values: 161.0 132.0 151.0 180.0 126.0 164.0 150.0 126.0 179.0 126.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 03:50:59 INFO - PROCESS | 26892 | Values: 162.0 161.0 162.0 162.0 162.0 163.0 162.0 162.0 162.0 163.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#3] audio-fft Cycles:10 Average:94.10 Median:76.00 stddev:38.25 (50.3%) stddev-sans-first:39.94 03:50:59 INFO - PROCESS | 26892 | Values: 75.0 76.0 76.0 172.0 77.0 75.0 76.0 76.0 161.0 77.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:84.50 stddev:29.43 (34.8%) stddev-sans-first:30.53 03:50:59 INFO - PROCESS | 26892 | Values: 85.0 85.0 151.0 85.0 85.0 84.0 134.0 84.0 83.0 149.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:50:59 INFO - PROCESS | 26892 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 118.0 117.0 118.0 117.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 03:50:59 INFO - PROCESS | 26892 | Values: 146.0 148.0 146.0 148.0 147.0 147.0 146.0 147.0 147.0 146.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 03:50:59 INFO - PROCESS | 26892 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:2.54 (4.7%) stddev-sans-first:2.62 03:50:59 INFO - PROCESS | 26892 | Values: 55.0 54.0 51.0 53.0 52.0 59.0 55.0 52.0 51.0 51.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.00 stddev:3.14 (5.2%) stddev-sans-first:3.31 03:50:59 INFO - PROCESS | 26892 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 70.0 60.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:80.00 stddev:10.48 (13.1%) stddev-sans-first:11.10 03:50:59 INFO - PROCESS | 26892 | Values: 81.0 79.0 75.0 76.0 77.0 83.0 84.0 77.0 111.0 83.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:126.50 stddev:8.34 (6.6%) stddev-sans-first:7.55 03:50:59 INFO - PROCESS | 26892 | Values: 139.0 123.0 132.0 121.0 141.0 117.0 118.0 128.0 122.0 125.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:160.00 stddev:2.72 (1.7%) stddev-sans-first:2.83 03:50:59 INFO - PROCESS | 26892 | Values: 160.0 155.0 160.0 160.0 158.0 164.0 156.0 156.0 157.0 160.0 03:50:59 INFO - PROCESS | 26892 | 03:50:59 INFO - PROCESS | 26892 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.50 stddev:1.97 (3.2%) stddev-sans-first:2.09 03:50:59 INFO - PROCESS | 26892 | Values: 62.0 66.0 61.0 61.0 61.0 63.0 60.0 61.0 61.0 65.0 03:50:59 INFO - PROCESS | 26892 | -------- Summary: end -------- 03:50:59 INFO - PROCESS | 26892 | 03:51:00 INFO - TEST-INFO | 26892: exit 0 03:51:00 INFO - TEST-OK | kraken | took 60997ms 03:51:00 INFO - SUITE-END | took 1171s 03:51:00 INFO - Completed test suite (00:19:31) 03:51:00 INFO - javascript benchmark 03:51:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7687.77651602096, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.6623337666238, 3426.537417448647, 3466.6533346665337, 3469.705075068619, 3475.131217195701, 5687.9715601421985, 5801.0718285074445, 5803.477687008169, 5862.179294898718, 5868.090445980869, 5808.448197421239, 5840.678762668052, 5887.735051922663, 5905.438983296591, 5910.911336329958, 5221.981713565856, 5273.209018647195, 5347.679139251657, 5367.812126575571, 5384.326959130109, 5118.9744051279695, 5229.555487783524, 5394.056040192951, 5403.243545903577, 5412.269343638598, 5264.315638966948, 5268.0517506848755, 5301.655392399482, 5317.12267475866, 5376.1398176291805, 5378.0, 5388.43421440749, 5394.8921021579345, 5394.91907621386, 5406.107992181324, 4986.725730084844, 5152.92270615941, 5219.634625576211, 5289.814856480005, 5327.760250788724, 5650.773969041234, 5691.9146212806845, 5701.600887937846, 5759.769609215627, 5787.102999035156, 6101.389861013863, 6134.693265336716, 6159.38406159385, 6179.19670442844, 6182.443580077815, 5915.763369465216, 6129.022607377584, 6154.784582539634, 6194.628322300677, 6215.533834962396, 5888.0, 5895.380984996556, 5900.291964964232, 5909.261342332208, 5922.2597175353085, 6447.129637498878, 6449.322821103717, 6465.967670161619, 6484.0, 6491.188601424822, 5868.853278667982, 6023.909641355383, 6055.031195008777, 6068.605540639845, 6165.260168779775, 6167.629942203482, 6198.566100372931, 6207.379262073757, 6214.9689251553455, 6217.751289948442, 6099.786507472217, 6126.877462450815, 6169.691515424211, 6171.814845554641, 6176.969115154486, 5980.073088671263, 6013.789517366871, 6033.276006879202, 6056.121862329937, 6113.96943015291, 4668.836590719308, 4670.929936050894, 4674.228752255908, 4675.4155730533685, 4695.718256904528, 4105.958940410617, 4149.585041495826, 4182.2890108681595, 4190.078182799779, 4217.430646862635, 5611.5230205433, 5930.28836539618, 5953.077273022688, 5977.491913187428, 6014.9699251504335, 6265.71804268809, 6279.748810047547, 6353.142325786052, 6396.648184349905, 6402.263739669905, 5832.358440571534, 6099.573029887954, 6103.389661033861, 6112.480439162632, 6131.724072416752, 5220.504052115043, 5273.1299335609965, 5303.708296043677, 5313.468653134656, 5344.95773324198, 6397.296297407282, 6430.871382572323, 6453.612783233021, 6481.351864813481, 6482.67586620667, 5997.850053748517, 6146.907796383147, 6251.093591429218, 6255.124282600531, 6276.058591211355, 6421.486281097595, 6552.410283074546, 6580.374864387875, 6585.769498067544, 6590.80227593173, 6169.938300616936, 6242.063690446469, 6249.187605611241, 6250.0937364082865, 6272.717727702173, 6060.363661815622, 6140.232470941132, 6206.379362063757, 6372.394622510854, 6379.170707807955, 6015.398460154124, 6045.395460453919, 6047.0929360596265, 6065.2418447694035, 6074.544409169242, 5962.165296858357, 6010.278766548042, 6024.036154215304, 6037.577369584263, 6051.909221361595, 5634.0, 5765.077587585966, 5770.3652598215, 5776.364599893925, 5909.2908850938165, 5977.133315669291, 5991.161237426851, 6000.609960352563, 6030.246219222598, 6045.78839740607, 5886.323072846506, 5888.3228428731245, 5905.527557795452, 5910.970445147746, 5927.703614819328], "value": 5721.479241153325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.905935050392, 640.57081755224, 642.9132067170931, 643.1574637225235, 651.7425616881332, 46429.76785116074, 47857.282140767915, 48016.759916200455, 48107.278390824125, 51662.225066623934, 42582.36126458106, 42848.0, 43241.35137972925, 43295.134097318034, 43374.349384759174, 9192.218661413788, 9206.49364284964, 9326.067393260688, 9344.53277336134, 9359.766005849875, 9087.182153606174, 9184.816303673922, 9192.678256261031, 9223.077692230756, 9230.353875228737, 9180.449173049605, 9200.171984521392, 9219.308551858603, 9260.536973151315, 9278.953605231964, 9180.449173049605, 9208.723738287828, 9243.983161852191, 9253.352265341362, 9262.02748711389, 9043.27653787702, 9119.361644684876, 9127.406718563272, 9132.08679132088, 9158.450492970444, 45149.322760158626, 45464.77267613674, 45491.772541137085, 45552.3167152493, 45842.77078614619, 3590.6409359063887, 3694.8522059117604, 3713.053171441265, 3735.477033215352, 3840.558335791392, 34152.31695366104, 34313.0, 34822.47766283508, 35533.82233088818, 36357.27285454276, 4617.145828021827, 4716.693414928019, 4743.241081426955, 4759.381280433556, 4862.294967229732, 353168.0, 353326.0, 354248.0, 354345.0, 355340.0, 35145.297094057976, 35777.0, 65931.0, 67000.6649966747, 67025.32974670191, 40864.0, 40952.38571421431, 40974.0, 41055.178896422505, 41134.38298425526, 17267.309307627806, 17301.221445034753, 17365.0, 17382.65234695299, 17435.302587896596, 14442.927785361006, 14512.492062777752, 14602.707945841235, 14631.487897923522, 14637.85362146365, 9251.444913305224, 9270.582823772946, 9304.581293841793, 9351.392159509745, 9399.718008459757, 3466.9306613868084, 3544.0962554548423, 3607.0801945503727, 3616.3726323154688, 3630.981845090758, 1112.5939032253332, 1131.462555286249, 1141.6061458796703, 1167.4629670351565, 1240.9813852792215, 33861.661383386345, 34200.31599368049, 34364.82817585946, 34432.82783586066, 34506.206690263825, 1138.4990604134155, 1192.6958625550603, 1234.234774439835, 1290.6966862787237, 1302.0169771822402, 17556.824431755518, 17629.0, 17785.377511787025, 17913.37303194408, 17996.640067198583, 582043.0, 889609.0, 898462.0, 905039.0, 912238.0, 66012.33987660061, 66379.0, 67069.32930670728, 67193.3280667187, 67203.66398168076, 1501.8197816262118, 1511.939522419113, 1514.3261248744423, 1528.5567185516297, 1529.1054732981108, 33757.15607109842, 34271.657283426845, 34732.47901281533, 35092.473612896334, 35099.0, 57199.71400143139, 57403.0, 57726.4227357721, 57893.13160302515, 57928.0, 533652.0, 539357.0, 540299.0, 542822.0, 544198.0, 31878.840605796824, 31963.0, 31971.84014080008, 32043.0, 32148.83925580357, 53014.0, 53314.73342633262, 53549.0, 53894.46105538894, 54441.45558544523, 44498.93134650429, 44615.55384446114, 45179.77410112928, 45349.54650453453, 46452.30321545112, 44363.11273774571, 44542.3318650227, 44802.0, 45504.31743523916, 45847.31229031501, 43525.12949740924, 44293.778531107135, 44377.33433998557, 44835.77582112199, 44853.77573112114, 39147.41278880762, 39297.60702392939, 39377.0, 39602.0, 39819.20361592694, 29748.851255743582, 29770.553441698827, 30022.099337019925, 30196.698033020268, 30234.697653023188], "value": 20701.05237143393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.7056516286342, 441.8232706917233, 443.348278031294, 444.1849206705695, 445.5544455544456, 18872.245110195592, 20052.498687532818, 20571.28000519982, 21196.788032119675, 21218.0, 19375.0, 19794.90102549485, 20343.389698309038, 20383.28658496953, 20447.0, 5786.045302525089, 5964.598319394939, 6017.428344307305, 6035.245594300713, 6042.0, 5594.524465420444, 5829.8251052468495, 5896.498797602209, 5907.793227237047, 5989.6406215626985, 5832.795852145176, 5937.168796368512, 5955.374685658008, 5976.073708575178, 6641.634710090943, 6598.208215014181, 6622.2715501294815, 6626.370494802986, 6660.766873159416, 6692.93307066928, 5125.359330083739, 5428.728563571805, 5449.3460784706085, 5532.28080349552, 5949.613275137102, 15183.316750746242, 15341.46304879335, 22957.426064348358, 24694.0, 24936.251912442473, 2311.768823117675, 2430.5503481855912, 2461.0770960889668, 2497.887595058227, 2520.8361456505454, 10862.45687715611, 11032.889671103347, 11260.0, 11266.943665281704, 11419.200655954086, 2075.2632815350635, 2085.384811480617, 2119.6078725435846, 2122.3208573256566, 2166.9783302166934, 76360.61819690921, 79195.20804791994, 80623.99999999942, 81597.36805263862, 91701.0829891706, 11537.769244615061, 11801.763964720829, 11861.46623401949, 11936.940315298369, 12003.459844307026, 16928.49214523566, 17006.57483562921, 17073.31706731717, 17092.2308496118, 17186.828131718772, 8664.610092545725, 8684.131586841266, 8833.60248788806, 8953.194212520773, 8979.910200897908, 8276.710315138942, 8305.501669899704, 8458.87311690325, 8471.534065626449, 8520.8721869172, 5142.61430392722, 5167.457416971164, 5220.190870415092, 5236.1098613235845, 5292.92060619091, 2114.661654135331, 2132.4455641533314, 2141.65733482642, 2154.64448366021, 2176.912923483043, 940.4075432477632, 999.2705325112563, 1064.9094826939643, 1077.0414331245197, 1138.6925530106826, 19682.21271149138, 20201.898990504953, 20209.89895050545, 20265.189392424432, 20391.286304979254, 971.9514024298757, 1053.7312985188728, 1063.0963680871198, 1074.08702602787, 1100.5047728522197, 11210.0, 11475.0, 11645.941770291094, 11647.475863586158, 11764.411779410995, 463205.0, 463325.0, 464238.0, 464517.0, 469850.0, 83244.0, 86108.56945715232, 101179.49410253049, 102341.0, 102942.48528757309, 1043.186314674555, 1048.9370637761758, 1059.7562560611102, 1089.6349722842779, 1101.2236373356764, 18337.358192463198, 18427.815721843148, 18462.26150953947, 18491.537711557317, 18535.53661158482, 44872.77563612161, 45366.31950520679, 45463.09073818571, 46422.53577464317, 47508.2873756887, 64708.0293795584, 65225.347746523825, 65321.0, 65970.67014665088, 66745.0, 8980.955095224483, 8996.145366190201, 9127.589258483516, 9200.493972831558, 9330.0, 7776.883346749916, 13015.739685206432, 13277.47309059432, 13920.791188132389, 14041.789373159616, 12361.876381236432, 12392.690182745504, 12434.129610927148, 12475.251484910934, 12782.0, 12567.434465448894, 12612.810807837706, 12615.432305546456, 12710.872891270968, 12996.285204313854, 12670.429830657828, 12684.746305073662, 13047.608571742863, 13171.384889030869, 13230.867691322963, 9460.668876589287, 9595.904040959502, 12751.872481275068, 13094.14888032275, 13261.734765304833, 11753.236039657395, 13096.476140954635, 13540.661483462598, 13680.179189248676, 13690.86309136896], "value": 9833.66417044664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.0520282627594, 1592.1879841280945, 1790.8925464472134, 1867.9906600466995, 1913.445100920733, 8028.638711257993, 8595.957020214899, 8916.420432671872, 9111.58997845097, 9139.268858491321, 8900.643974241038, 9129.68046118386, 9139.954300228506, 9151.77120571986, 9206.907930920688, 2449.0326321103153, 2464.2607217834666, 2469.086438017936, 2469.604863221885, 2480.5659009673327, 2404.3748625357443, 2479.752024797524, 2491.165459571046, 2501.462185630089, 2503.5869081601513, 2461.618449140386, 2468.456939473322, 2514.53481105996, 2518.0431436054273, 2522.3567990162483, 2417.359399759065, 2437.4150203951103, 2441.6581678565017, 2444.278937713379, 2456.7788898999174, 2403.783659470656, 2411.397150712322, 2420.322309753272, 2420.7095148582107, 2466.062896099471, 9199.58601862911, 9257.259419246444, 9267.629294828199, 9276.675316363895, 9286.860697089549, 5654.943450565483, 5788.710564471801, 5798.681072541009, 5799.130130480419, 5810.5932584719085, 3445.448728203475, 3508.719302455823, 3589.5513060867393, 3607.134287770942, 3664.395374763161, 1314.3147739447716, 1339.7521458530202, 1348.6763176837587, 2284.7486776454575, 2375.904963801463, 12821.423035963404, 12901.419436125398, 12966.286854223014, 13050.86949130506, 13085.542006029744, 4797.232442809134, 4798.376211092535, 4887.218045112836, 4911.0, 4938.308636790852, 10076.798464030679, 10134.442605656759, 10144.644937427302, 10175.389476631426, 10216.642417515352, 5630.436956304418, 5635.915461268084, 5653.773849046074, 5655.0386434306265, 5659.037963546125, 4405.757683327383, 5112.693238405707, 5201.271821944931, 5249.501297376743, 5285.07511185541, 3790.450384694204, 3911.4523966644692, 3918.9608103919168, 3943.5662077171487, 3987.481627388479, 1210.2254557083475, 1222.9143959922899, 1238.8699186585459, 1249.8187762774528, 1264.5826877130526, 1008.6368907193405, 1043.2592859069994, 1049.7113293844102, 1056.9471526423647, 1061.639042725477, 6294.307626161119, 6379.585326953733, 6386.84032899172, 6448.226212854487, 6456.451201647913, 478.6410192355733, 480.5530856303671, 482.94929032451086, 496.1515807968415, 496.73424717775816, 4235.682323825726, 4241.63946064588, 4282.164977829356, 4329.393884856123, 4407.184670835905, 11329.60346387889, 11665.591704290478, 11698.47356868926, 11701.707457313465, 11724.120690948213, 11585.246958947642, 11604.6518604442, 11627.88372116268, 11639.41802909868, 11664.766704666028, 8129.4715843469985, 8382.203690649378, 8388.245057944692, 8536.402451828437, 8795.296376289882, 6393.904091438725, 6647.202335719745, 6676.76631317921, 6779.6271205084195, 6780.660966951534, 10491.265611407434, 10632.25574209798, 10835.0, 10860.131189504662, 10987.670369888916, 10770.569177232823, 10971.286866353661, 10980.231383803057, 10994.505247263733, 11008.779824403628, 7302.123745150616, 8060.11338752725, 8313.750587482386, 8378.622961966848, 8433.072362040048, 9193.448393096436, 9235.630574776933, 9305.255579553484, 9339.066093390607, 9348.859767103635, 8009.8398032040195, 8156.265936065783, 8160.347172226089, 8200.507969521848, 8284.792880178044, 8128.674853005814, 8139.430239883388, 8221.83556328882, 8245.670173193004, 8282.047564530156, 7509.4367922404945, 7584.886226706714, 7653.464257501922, 7682.116556596063, 7701.229877012254, 5496.285482887199, 5539.113741801292, 5603.691796951207, 5657.6888271143835, 5695.572832037531, 5626.971865140812, 5632.352279487911, 5683.5737319700365, 5701.771929122789, 5888.587798854039], "value": 4869.706011965008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.79864675105307, 365.17288341905606, 366.97064234861216, 370.3019807662556, 370.6238168259217, 5689.573282003846, 5733.598648094635, 5813.8024086752275, 5867.6479411235305, 5875.823725288237, 5640.182173584828, 5687.2606561147095, 5699.774548472078, 5808.41915808419, 5810.622309549887, 4519.932201016987, 4535.841245556406, 4542.909141817178, 4566.452025756914, 4601.953980460186, 4505.639548836085, 4552.339910712944, 4582.1064892346, 4586.44962604488, 4604.148232576984, 4513.165064463068, 4519.367288579601, 4524.773761311938, 4539.114872599845, 4559.680822342438, 4361.978190109061, 4508.2561377372695, 4532.818687252522, 4536.56902594253, 4547.340635607835, 3939.527256729182, 3940.822662980173, 3949.4273330367223, 3952.7628342299554, 3957.762534247954, 5595.608307418483, 5627.493525582676, 5649.604527683064, 5667.773289068432, 5704.429557044304, 5127.589792816566, 5201.323827902386, 5229.5293423591675, 5233.476652334774, 5236.895262094737, 6015.699215039274, 6045.428188670933, 6074.48366888815, 6114.327423983358, 6116.256866792943, 4356.324769660708, 4394.1870753910625, 4402.185595664812, 4423.248047831877, 4443.311286750559, 6080.7805151119965, 6331.936680633181, 6382.521310901701, 6387.393197646216, 6410.634416931101, 4427.734335939854, 4433.135538570013, 4459.130469558405, 4485.125400546862, 4491.585150677526, 4525.389072475239, 4537.8411755588395, 4566.269396896547, 4570.771461426915, 4579.450465944108, 4958.256261560723, 5192.428832828386, 5205.947940520622, 5212.9739351303, 5226.007058658843, 5820.24336836217, 5931.406859314034, 6066.241719785027, 6093.2078829751845, 6113.602615829956, 1532.8160620725362, 1537.7232098222428, 1550.1396724817835, 1555.898866573669, 1559.6976524602085, 986.2297176568078, 988.1748739802346, 990.6829814459304, 996.5415908682088, 1019.6380284998867, 5165.295452500666, 5225.843224703265, 5232.0, 5288.334174734946, 5316.3354580677415, 5418.485243901823, 5546.889062218814, 5550.333959924754, 5594.608377413542, 5601.747921343549, 4897.2898929655, 5214.582833373321, 5327.38735045467, 5329.333833270841, 5360.463953604608, 2915.752161066333, 2952.0, 2965.9703402966124, 2976.895808646687, 3004.2339203502966, 5633.915491267634, 5694.487496125369, 5701.71491425427, 5764.942350576524, 5798.536117110621, 5877.118432235199, 6405.487560995202, 6405.935940640534, 6409.692510696145, 6434.806955791334, 6588.275289718127, 6603.70283337251, 6616.96691516549, 6635.668216589248, 6661.2006559213205, 6114.266288045285, 6217.564770466205, 6254.968725156527, 6290.402411770874, 6373.904391434039, 6165.290991536031, 6207.96896015517, 6209.316975132822, 6221.657808820377, 6228.190335256388, 6387.4570661492435, 6413.743450262124, 6488.967555162194, 6508.60948343101, 6560.770373036921, 4462.367203350039, 4479.619232365286, 4484.68607197493, 4489.610344190246, 4520.299353600197, 6156.969215153896, 6282.089097080989, 6314.968425157845, 6317.915088021034, 6325.335839736761, 6087.573869829246, 6104.565427124533, 6106.57253992234, 6108.267007959073, 6120.449159575482, 6034.63792172471, 6035.185249991195, 6047.941610218282, 6079.665618391031, 6123.908141377972, 5650.23721797552, 5930.318013428338, 5941.752232031322, 5959.139612985705, 5987.850303742442, 6086.3305036446845, 6097.481714054177, 6106.2061931948565, 6107.511399088151, 6156.907646385218, 5722.885542289214, 5944.821655350346, 5978.940210597838, 6019.909701354396, 6023.337432882292], "value": 4574.8828321295905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.2658851265962, 777.3392616276166, 867.9566021698913, 879.9560021998899, 890.6481939633845, 15931.203439828007, 17587.472375828747, 17622.0, 17656.735148972777, 17659.55851103723, 16186.190690465488, 16233.756493652605, 16283.0, 16317.184140792971, 16354.0, 882.5498995512303, 883.1521739130443, 893.1113542025694, 897.344938195118, 905.8143080668447, 900.5722281916103, 904.0552622509493, 905.732808821396, 905.782612173077, 908.2960705453294, 944.1219665710886, 950.0831697411996, 950.9524523773819, 953.4041224234853, 953.7138858342504, 931.4318265857821, 933.7292185266265, 937.5218638494349, 940.8118376324728, 943.4622265308777, 687.9724811007553, 688.3529482286634, 688.6900894597451, 690.9032735417046, 691.4468425259772, 15266.694666106727, 15343.539693809093, 15385.153816540085, 15432.459863904709, 15463.304151313105, 13810.516631917935, 13937.372818223204, 13940.302984850818, 14534.636634084127, 14759.114453132739, 13336.599902002956, 13468.39392227352, 13535.052546321762, 13558.661033474144, 13695.657608559766, 4105.4252404663375, 4738.786754596052, 4760.214564596872, 4831.975840120777, 4844.612431005511, 63530.36469635291, 65678.34321656771, 65821.0, 76183.0, 78307.21692783057, 12106.273623582612, 12113.39433028345, 12159.939200303941, 12222.20555663879, 12340.074494412955, 22665.320040398816, 22920.541589168122, 22958.885205573864, 22964.540709185723, 22990.540189196458, 8624.482531048032, 8686.956565217133, 8779.165979231962, 9131.726048218565, 9146.070445817317, 4755.38180036593, 4774.809007639725, 4850.320955066293, 4955.083309587738, 4997.775100120503, 3813.923721525554, 3937.9803100984886, 3945.112349721347, 3950.7827069511454, 3959.524857017119, 659.868026394723, 661.8841702702105, 666.643345809999, 673.0778832998823, 680.8434060166189, 638.9297177310492, 762.8398036412416, 788.4756636836554, 794.6265255329986, 804.3926835239474, 10744.247902646895, 10754.487677564595, 10981.3960232188, 10995.780084398268, 11138.443077846076, 789.553902045354, 814.3973459639824, 814.5153633588005, 823.8928939237861, 824.7608193623902, 4784.210605250095, 4952.306677065142, 4984.700917944935, 4993.90012199754, 5036.370453693288, 69270.65364673144, 96927.51536242274, 98258.0, 99775.50112249392, 112952.0, 63701.0, 80645.0, 95316.0, 97888.0, 101028.0, 58293.0, 75901.24098758942, 85402.57298713467, 90201.0, 92010.53994729984, 10127.392356458637, 10155.339902906288, 10263.538140783534, 10503.684889453329, 10550.208734345108, 37574.624253757116, 45253.321200181366, 45271.0, 52267.73866130645, 52333.953320932604, 76310.23689763031, 76353.61823190849, 77845.0, 79702.6014869922, 79827.0, 12771.0, 13424.93287533556, 13570.389332479865, 13636.727265454438, 13843.169409835442, 15105.54683359501, 15448.613784655474, 15512.0, 15516.146611936452, 15549.84450155484, 13241.3379331035, 13606.455741770218, 14202.218877961812, 14416.639584010483, 14669.119852808866, 14865.331060102106, 15258.771118433011, 15373.615659608151, 15523.84476155224, 15629.218539073228, 13640.590782276548, 13840.0, 14880.0, 14980.550583482078, 15006.2496875158, 11198.328100314007, 11595.246308989888, 11666.416679165837, 11796.233244839059, 11876.82184767212, 11217.158713096387, 11883.524659013543, 12096.395180240777, 12113.818292725793, 12166.45250963691], "value": 7955.4520670237, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 111.0, 111.0, 112.0, 112.0, 110.0, 112.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 132.0, 151.0, 180.0, 126.0, 164.0, 150.0, 126.0, 179.0, 126.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 163.0, 162.0, 162.0, 162.0, 163.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 172.0, 77.0, 75.0, 76.0, 76.0, 161.0, 77.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 151.0, 85.0, 85.0, 84.0, 134.0, 84.0, 83.0, 149.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 148.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 51.0, 53.0, 52.0, 59.0, 55.0, 52.0, 51.0, 51.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 70.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 75.0, 76.0, 77.0, 83.0, 84.0, 77.0, 111.0, 83.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 123.0, 132.0, 121.0, 141.0, 117.0, 118.0, 128.0, 122.0, 125.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 155.0, 160.0, 160.0, 158.0, 164.0, 156.0, 156.0, 157.0, 160.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 61.0, 61.0, 61.0, 63.0, 60.0, 61.0, 61.0, 65.0], "value": 62.1, "unit": "score"}]}]} 03:51:00 INFO - Return code: 0 03:51:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:00 ERROR - # TBPL SUCCESS # 03:51:00 INFO - Running post-action listener: _resource_record_post_action 03:51:00 INFO - ##### 03:51:00 INFO - ##### Finished run-tests step (success) 03:51:00 INFO - ##### 03:51:00 INFO - Running post-run listener: _resource_record_post_run 03:51:00 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 274432; Write bytes: 391450624; Read time: 56; Write time: 266264 03:51:00 INFO - TinderboxPrint: CPU usage
13.4% 03:51:00 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 56 03:51:00 INFO - TinderboxPrint: I/O write bytes / time
391,450,624 / 266,264 03:51:00 INFO - TinderboxPrint: CPU idle
8,210.3 (86.6%) 03:51:00 INFO - TinderboxPrint: CPU user
1,212.9 (12.8%) 03:51:00 INFO - TinderboxPrint: Swap in / out
0 / 0 03:51:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 119222272; Read time: 0; Write time: 98712 03:51:00 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 274432; Write bytes: 252567552; Read time: 56; Write time: 146112 03:51:01 INFO - Running post-run listener: _upload_blobber_files 03:51:01 INFO - Blob upload gear active. 03:51:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:01 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 03:51:01 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'] 03:51:01 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 03:51:01 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:51:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:51:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:02 INFO - (blobuploader) - INFO - Done attempting. 03:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:51:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:05 INFO - (blobuploader) - INFO - Done attempting. 03:51:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:51:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:51:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:06 INFO - (blobuploader) - INFO - Done attempting. 03:51:06 INFO - (blobuploader) - INFO - Iteration through files over. 03:51:06 INFO - Return code: 0 03:51:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:51:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:51:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e38acc6273bd90f10ceef588c75739c07c0eeafd9c422cd43a4e6a6daaaa0544922f62dc40c773086b5bea88ca2f22d29992de71d3415d0f90cc733fe7e4fa47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8122dfaf9f51d54e192e32cd9b397aeb44d1fc478011492eaf128e69654be9146cba4265d3e3bc212367094b5702dcd0dc32bab9453c82f7641129844724532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83f2af7453dd30fc0487c20766b981a7e966938a21819e3fb876e1b7df19513cf32eae1bfcdbe46468853e7ecbef507959420e679939fda2c871f9d58aa56aff"} 03:51:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:51:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:51:06 INFO - Contents: 03:51:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e38acc6273bd90f10ceef588c75739c07c0eeafd9c422cd43a4e6a6daaaa0544922f62dc40c773086b5bea88ca2f22d29992de71d3415d0f90cc733fe7e4fa47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8122dfaf9f51d54e192e32cd9b397aeb44d1fc478011492eaf128e69654be9146cba4265d3e3bc212367094b5702dcd0dc32bab9453c82f7641129844724532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83f2af7453dd30fc0487c20766b981a7e966938a21819e3fb876e1b7df19513cf32eae1bfcdbe46468853e7ecbef507959420e679939fda2c871f9d58aa56aff"} 03:51:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:51:06 INFO - Copying logs to upload dir... 03:51:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:51:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.931219 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-27 03:51:06.374362) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:06.378004) ========= 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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e38acc6273bd90f10ceef588c75739c07c0eeafd9c422cd43a4e6a6daaaa0544922f62dc40c773086b5bea88ca2f22d29992de71d3415d0f90cc733fe7e4fa47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8122dfaf9f51d54e192e32cd9b397aeb44d1fc478011492eaf128e69654be9146cba4265d3e3bc212367094b5702dcd0dc32bab9453c82f7641129844724532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83f2af7453dd30fc0487c20766b981a7e966938a21819e3fb876e1b7df19513cf32eae1bfcdbe46468853e7ecbef507959420e679939fda2c871f9d58aa56aff"} build_url:https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e38acc6273bd90f10ceef588c75739c07c0eeafd9c422cd43a4e6a6daaaa0544922f62dc40c773086b5bea88ca2f22d29992de71d3415d0f90cc733fe7e4fa47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8122dfaf9f51d54e192e32cd9b397aeb44d1fc478011492eaf128e69654be9146cba4265d3e3bc212367094b5702dcd0dc32bab9453c82f7641129844724532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83f2af7453dd30fc0487c20766b981a7e966938a21819e3fb876e1b7df19513cf32eae1bfcdbe46468853e7ecbef507959420e679939fda2c871f9d58aa56aff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:06.407294) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:06.408113) ========= 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=ed14279e38b05397cf4aa391000001a4-1467023431.868356-1272153112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004728 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:06.490602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 03:51:06.491299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 03:51:06.492035) ========= ========= Total master_lag: 0.27 =========