builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1467023457.32 results: success (0) buildid: 20160627004013 builduid: 23f3ae60fcd94072a6178f2a56f82a27 revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.316458) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.316960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.317294) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.335757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.336156) ========= 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=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 03:30:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 31.4M=0s 2016-06-27 03:30:58 (31.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.263990 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.614552) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.614984) ========= 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=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019647 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.665773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:57.666234) ========= 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=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 03:30:58,746 truncating revision to first 12 chars 2016-06-27 03:30:58,746 Setting DEBUG logging. 2016-06-27 03:30:58,746 attempt 1/10 2016-06-27 03:30:58,746 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:59,062 unpacking tar archive at: mozilla-aurora-afcdd8539963/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628817 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:58.309388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:58.309875) ========= script_repo_revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:58.310354) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:58.310874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 03:30:58.322504) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-27 03:30:58.322915) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:59 INFO - MultiFileLogger online at 20160627 03:30:59 in /builds/slave/test 03:30:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:30:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:59 INFO - {'append_to_log': False, 03:30:59 INFO - 'base_work_dir': '/builds/slave/test', 03:30:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:59 INFO - 'branch': 'Mozilla-Aurora', 03:30:59 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:59 INFO - 'config_files': ('talos/linux_config.py',), 03:30:59 INFO - 'default_actions': ('clobber', 03:30:59 INFO - 'read-buildbot-config', 03:30:59 INFO - 'download-and-extract', 03:30:59 INFO - 'populate-webroot', 03:30:59 INFO - 'create-virtualenv', 03:30:59 INFO - 'install', 03:30:59 INFO - 'run-tests'), 03:30:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:59 INFO - 'download_minidump_stackwalk': True, 03:30:59 INFO - 'download_symbols': 'ondemand', 03:30:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:59 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:59 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:59 INFO - 'installer_path': 'installer.exe', 03:30:59 INFO - 'log_level': 'info', 03:30:59 INFO - 'log_name': 'talos', 03:30:59 INFO - 'log_to_console': True, 03:30:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:59 INFO - 'opt_config_files': (), 03:30:59 INFO - 'pip_index': False, 03:30:59 INFO - 'sps_profile': False, 03:30:59 INFO - 'sps_profile_interval': 0, 03:30:59 INFO - 'suite': 'dromaeojs', 03:30:59 INFO - 'system_bits': '32', 03:30:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:59 INFO - 'title': 'talos-linux64-ix-015', 03:30:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:59 INFO - 'use_talos_json': True, 03:30:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:59 INFO - 'work_dir': 'build'} 03:30:59 INFO - ##### 03:30:59 INFO - ##### Running clobber step. 03:30:59 INFO - ##### 03:30:59 INFO - Running pre-action listener: _resource_record_pre_action 03:30:59 INFO - Running main action method: clobber 03:30:59 INFO - rmtree: /builds/slave/test/build 03:30:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:31:00 INFO - Running post-action listener: _resource_record_post_action 03:31:00 INFO - ##### 03:31:00 INFO - ##### Finished clobber step (success) 03:31:00 INFO - ##### 03:31:00 INFO - ##### 03:31:00 INFO - ##### Running read-buildbot-config step. 03:31:00 INFO - ##### 03:31:00 INFO - Running pre-action listener: _resource_record_pre_action 03:31:00 INFO - Running main action method: read_buildbot_config 03:31:00 INFO - Using buildbot properties: 03:31:00 INFO - { 03:31:00 INFO - "project": "", 03:31:00 INFO - "product": "firefox", 03:31:00 INFO - "script_repo_revision": "production", 03:31:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:31:00 INFO - "repository": "", 03:31:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:31:00 INFO - "buildid": "20160627004013", 03:31:00 INFO - "stage_platform": "linux64-pgo", 03:31:00 INFO - "basedir": "/builds/slave/test", 03:31:00 INFO - "nightly_build": "True", 03:31:00 INFO - "buildnumber": 574, 03:31:00 INFO - "slavename": "talos-linux64-ix-015", 03:31:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:31:00 INFO - "platform": "ubuntu64_hw", 03:31:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:31:00 INFO - "branch": "mozilla-aurora", 03:31:00 INFO - "repo_path": "releases/mozilla-aurora", 03:31:00 INFO - "revision": "afcdd85399636b518887cc17aedbda8cc1c1f197", 03:31:00 INFO - "pgo_build": "True", 03:31:00 INFO - "builduid": "23f3ae60fcd94072a6178f2a56f82a27", 03:31:00 INFO - "slavebuilddir": "test" 03:31:00 INFO - } 03:31:00 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:31:00 INFO - Running post-action listener: _resource_record_post_action 03:31:00 INFO - ##### 03:31:00 INFO - ##### Finished read-buildbot-config step (success) 03:31:00 INFO - ##### 03:31:00 INFO - ##### 03:31:00 INFO - ##### Running download-and-extract step. 03:31:00 INFO - ##### 03:31:00 INFO - Running pre-action listener: _resource_record_pre_action 03:31:00 INFO - Running main action method: download_and_extract 03:31:00 INFO - mkdir: /builds/slave/test/build/tests 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 - 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:31:00 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:31:00 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:31:00 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:31:00 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:31:01 INFO - Downloaded 1361 bytes. 03:31:01 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:31:01 INFO - Using the following test package requirements: 03:31:01 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:01 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:31:01 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:31:01 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'jsshell-linux-x86_64.zip'], 03:31:01 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:31:01 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:01 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:31:01 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:31:01 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:31:01 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:31:01 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:31:01 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:01 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:31:01 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:31:01 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:31:01 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:31:01 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:31:03 INFO - Downloaded 24516514 bytes. 03:31:03 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:31:03 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:31:04 INFO - Return code: 0 03:31:04 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: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 - 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:31:04 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:31:04 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:31:04 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:31:04 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:31:06 INFO - Downloaded 24516514 bytes. 03:31:06 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:31:06 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:31:08 INFO - Return code: 0 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 - 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:31:08 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:31:08 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:31:08 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:31:08 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:31:09 INFO - Downloaded 10833369 bytes. 03:31:09 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:31:09 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:31:09 INFO - Return code: 0 03:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:09 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:31:09 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:31:09 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:31:09 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:31:09 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:31:10 INFO - Downloaded 63616255 bytes. 03:31:10 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:31:10 INFO - mkdir: /builds/slave/test/properties 03:31:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:10 INFO - Contents: 03:31:10 INFO - build_url:https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:11 INFO - Running post-action listener: _resource_record_post_action 03:31:11 INFO - Running post-action listener: set_extra_try_arguments 03:31:11 INFO - ##### 03:31:11 INFO - ##### Finished download-and-extract step (success) 03:31:11 INFO - ##### 03:31:11 INFO - ##### 03:31:11 INFO - ##### Running populate-webroot 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: populate_webroot 03:31:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:11 INFO - '--tppagecycles', 03:31:11 INFO - '7'], 03:31:11 INFO - 'tests': ['tsvgm']}}, 03:31:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['damp', 'tps']}, 03:31:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'tests': ['damp', 'tps']}, 03:31:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['dromaeo_dom']}, 03:31:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['a11yr', 03:31:11 INFO - 'ts_paint', 03:31:11 INFO - 'tpaint', 03:31:11 INFO - 'sessionrestore', 03:31:11 INFO - 'sessionrestore_no_auto_restore', 03:31:11 INFO - 'tabpaint']}, 03:31:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['tsvgx', 03:31:11 INFO - 'tsvgr_opacity', 03:31:11 INFO - 'tart', 03:31:11 INFO - 'tscrollx', 03:31:11 INFO - 'cart']}, 03:31:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:11 INFO - 'tsvgr_opacity', 03:31:11 INFO - 'tart', 03:31:11 INFO - 'tscrollx', 03:31:11 INFO - 'cart']}, 03:31:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'talos_options': ['--disable-e10s'], 03:31:11 INFO - 'tests': ['tp5o']}, 03:31:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'tests': ['tp5o']}, 03:31:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'talos_options': ['--disable-e10s', 03:31:11 INFO - '--xperf_path', 03:31:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:11 INFO - 'tests': ['tp5n']}, 03:31:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:11 INFO - 'talos_options': ['--xperf_path', 03:31:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:11 INFO - 'tests': ['tp5n']}}, 03:31:11 INFO - 'talos.zip': {'path': '', 03:31:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:11 INFO - Running post-action listener: _resource_record_post_action 03:31:11 INFO - ##### 03:31:11 INFO - ##### Finished populate-webroot step (success) 03:31:11 INFO - ##### 03:31:11 INFO - ##### 03:31:11 INFO - ##### Running create-virtualenv 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: create_virtualenv 03:31:11 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:11 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:31:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:11 INFO - Using real prefix '/usr' 03:31:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:12 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:14 INFO - Installing pip.................done. 03:31:14 INFO - Return code: 0 03:31:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:14 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:31:14 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:31:14 INFO - Using env: {'DISPLAY': ':0', 03:31:14 INFO - 'HOME': '/home/cltbld', 03:31:14 INFO - 'LANG': 'en_US.UTF-8', 03:31:14 INFO - 'LANGUAGE': 'en_US:en', 03:31:14 INFO - 'LOGNAME': 'cltbld', 03:31:14 INFO - 'MAIL': '/var/mail/cltbld', 03:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:14 INFO - 'MOZ_NO_REMOTE': '1', 03:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:14 INFO - 'NO_EM_RESTART': '1', 03:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:14 INFO - 'PWD': '/builds/slave/test', 03:31:14 INFO - 'SHELL': '/bin/bash', 03:31:14 INFO - 'SHLVL': '1', 03:31:14 INFO - 'TERM': 'linux', 03:31:14 INFO - 'TMOUT': '86400', 03:31:14 INFO - 'USER': 'cltbld', 03:31:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:14 INFO - '_': '/tools/buildbot/bin/python'} 03:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:14 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:31:14 INFO - Cleaning up... 03:31:14 INFO - Return code: 0 03:31:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:14 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:31:14 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:31:14 INFO - Using env: {'DISPLAY': ':0', 03:31:14 INFO - 'HOME': '/home/cltbld', 03:31:14 INFO - 'LANG': 'en_US.UTF-8', 03:31:14 INFO - 'LANGUAGE': 'en_US:en', 03:31:14 INFO - 'LOGNAME': 'cltbld', 03:31:14 INFO - 'MAIL': '/var/mail/cltbld', 03:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:14 INFO - 'MOZ_NO_REMOTE': '1', 03:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:14 INFO - 'NO_EM_RESTART': '1', 03:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:14 INFO - 'PWD': '/builds/slave/test', 03:31:14 INFO - 'SHELL': '/bin/bash', 03:31:14 INFO - 'SHLVL': '1', 03:31:14 INFO - 'TERM': 'linux', 03:31:14 INFO - 'TMOUT': '86400', 03:31:14 INFO - 'USER': 'cltbld', 03:31:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:14 INFO - '_': '/tools/buildbot/bin/python'} 03:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:14 INFO - Downloading/unpacking psutil>=3.1.1 03:31:14 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:14 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:14 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:14 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:14 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:14 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:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:16 INFO - Installing collected packages: psutil 03:31:16 INFO - Running setup.py install for psutil 03:31:16 INFO - building 'psutil._psutil_linux' extension 03:31:16 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:31:16 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:31:16 INFO - building 'psutil._psutil_posix' extension 03:31:16 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:31:17 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:31:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:17 INFO - Successfully installed psutil 03:31:17 INFO - Cleaning up... 03:31:17 INFO - Return code: 0 03:31:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:31:17 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:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:17 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:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:17 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:17 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:31:17 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:31:17 INFO - Using env: {'DISPLAY': ':0', 03:31:17 INFO - 'HOME': '/home/cltbld', 03:31:17 INFO - 'LANG': 'en_US.UTF-8', 03:31:17 INFO - 'LANGUAGE': 'en_US:en', 03:31:17 INFO - 'LOGNAME': 'cltbld', 03:31:17 INFO - 'MAIL': '/var/mail/cltbld', 03:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:17 INFO - 'MOZ_NO_REMOTE': '1', 03:31:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:17 INFO - 'NO_EM_RESTART': '1', 03:31:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:17 INFO - 'PWD': '/builds/slave/test', 03:31:17 INFO - 'SHELL': '/bin/bash', 03:31:17 INFO - 'SHLVL': '1', 03:31:17 INFO - 'TERM': 'linux', 03:31:17 INFO - 'TMOUT': '86400', 03:31:17 INFO - 'USER': 'cltbld', 03:31:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:17 INFO - '_': '/tools/buildbot/bin/python'} 03:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:31:17 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:17 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:17 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:17 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:17 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:17 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:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:31:19 INFO - Installing collected packages: mozsystemmonitor 03:31:19 INFO - Running setup.py install for mozsystemmonitor 03:31:19 INFO - Successfully installed mozsystemmonitor 03:31:19 INFO - Cleaning up... 03:31:19 INFO - Return code: 0 03:31:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:19 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:19 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:31:19 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:31:19 INFO - Using env: {'DISPLAY': ':0', 03:31:19 INFO - 'HOME': '/home/cltbld', 03:31:19 INFO - 'LANG': 'en_US.UTF-8', 03:31:19 INFO - 'LANGUAGE': 'en_US:en', 03:31:19 INFO - 'LOGNAME': 'cltbld', 03:31:19 INFO - 'MAIL': '/var/mail/cltbld', 03:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:19 INFO - 'MOZ_NO_REMOTE': '1', 03:31:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:19 INFO - 'NO_EM_RESTART': '1', 03:31:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:19 INFO - 'PWD': '/builds/slave/test', 03:31:19 INFO - 'SHELL': '/bin/bash', 03:31:19 INFO - 'SHLVL': '1', 03:31:19 INFO - 'TERM': 'linux', 03:31:19 INFO - 'TMOUT': '86400', 03:31:19 INFO - 'USER': 'cltbld', 03:31:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:19 INFO - '_': '/tools/buildbot/bin/python'} 03:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:19 INFO - Downloading/unpacking jsonschema==2.5.1 03:31:19 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:19 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:19 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:19 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:19 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:19 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:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:31:21 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:21 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:21 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:21 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:21 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:21 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:21 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:21 INFO - warning: no files found matching '*.txt' 03:31:21 INFO - no previously-included directories found matching 'build' 03:31:21 INFO - no previously-included directories found matching 'dist' 03:31:21 INFO - no previously-included directories found matching '.git*' 03:31:21 INFO - Installing collected packages: jsonschema, functools32 03:31:21 INFO - Running setup.py install for functools32 03:31:22 INFO - warning: no files found matching '*.txt' 03:31:22 INFO - no previously-included directories found matching 'build' 03:31:22 INFO - no previously-included directories found matching 'dist' 03:31:22 INFO - no previously-included directories found matching '.git*' 03:31:22 INFO - Successfully installed jsonschema functools32 03:31:22 INFO - Cleaning up... 03:31:22 INFO - Return code: 0 03:31:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:31:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:22 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:22 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:31:22 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:31:22 INFO - Using env: {'DISPLAY': ':0', 03:31:22 INFO - 'HOME': '/home/cltbld', 03:31:22 INFO - 'LANG': 'en_US.UTF-8', 03:31:22 INFO - 'LANGUAGE': 'en_US:en', 03:31:22 INFO - 'LOGNAME': 'cltbld', 03:31:22 INFO - 'MAIL': '/var/mail/cltbld', 03:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:22 INFO - 'MOZ_NO_REMOTE': '1', 03:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:22 INFO - 'NO_EM_RESTART': '1', 03:31:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:22 INFO - 'PWD': '/builds/slave/test', 03:31:22 INFO - 'SHELL': '/bin/bash', 03:31:22 INFO - 'SHLVL': '1', 03:31:22 INFO - 'TERM': 'linux', 03:31:22 INFO - 'TMOUT': '86400', 03:31:22 INFO - 'USER': 'cltbld', 03:31:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:22 INFO - '_': '/tools/buildbot/bin/python'} 03:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:22 INFO - Downloading/unpacking blobuploader==1.2.4 03:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - Downloading docopt-0.6.1.tar.gz 03:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:24 INFO - Running setup.py install for blobuploader 03:31:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:25 INFO - Running setup.py install for requests 03:31:25 INFO - Running setup.py install for docopt 03:31:25 INFO - Successfully installed blobuploader requests docopt 03:31:25 INFO - Cleaning up... 03:31:25 INFO - Return code: 0 03:31:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:25 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:25 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:25 INFO - Using env: {'DISPLAY': ':0', 03:31:25 INFO - 'HOME': '/home/cltbld', 03:31:25 INFO - 'LANG': 'en_US.UTF-8', 03:31:25 INFO - 'LANGUAGE': 'en_US:en', 03:31:25 INFO - 'LOGNAME': 'cltbld', 03:31:25 INFO - 'MAIL': '/var/mail/cltbld', 03:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:25 INFO - 'MOZ_NO_REMOTE': '1', 03:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:25 INFO - 'NO_EM_RESTART': '1', 03:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:25 INFO - 'PWD': '/builds/slave/test', 03:31:25 INFO - 'SHELL': '/bin/bash', 03:31:25 INFO - 'SHLVL': '1', 03:31:25 INFO - 'TERM': 'linux', 03:31:25 INFO - 'TMOUT': '86400', 03:31:25 INFO - 'USER': 'cltbld', 03:31:25 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:25 INFO - '_': '/tools/buildbot/bin/python'} 03:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:25 INFO - Running setup.py (path:/tmp/pip-qALH1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:25 INFO - Running setup.py (path:/tmp/pip-42oYSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:25 INFO - Running setup.py (path:/tmp/pip-9lWKh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:26 INFO - Running setup.py (path:/tmp/pip-dbubv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:26 INFO - Running setup.py (path:/tmp/pip-rqgmiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:26 INFO - Running setup.py (path:/tmp/pip-XKLJrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:26 INFO - Running setup.py (path:/tmp/pip-TG8_BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:26 INFO - Running setup.py (path:/tmp/pip-AlvSQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:26 INFO - Running setup.py (path:/tmp/pip-JWDKU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:26 INFO - Running setup.py (path:/tmp/pip-LjyDbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:26 INFO - Running setup.py (path:/tmp/pip-XZUkar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:26 INFO - Running setup.py (path:/tmp/pip-teh6Vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:26 INFO - Running setup.py (path:/tmp/pip-SCHe2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:26 INFO - Running setup.py (path:/tmp/pip-KzfpBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:26 INFO - Running setup.py (path:/tmp/pip-4YwfDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:26 INFO - Running setup.py (path:/tmp/pip-pMM4Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:26 INFO - Running setup.py (path:/tmp/pip-vGKwHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:27 INFO - Running setup.py install for manifestparser 03:31:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Running setup.py install for mozcrash 03:31:27 INFO - Running setup.py install for mozdebug 03:31:27 INFO - Running setup.py install for mozdevice 03:31:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Running setup.py install for mozfile 03:31:27 INFO - Running setup.py install for mozhttpd 03:31:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Running setup.py install for mozinfo 03:31:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Running setup.py install for mozInstall 03:31:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:27 INFO - Running setup.py install for mozleak 03:31:27 INFO - Running setup.py install for mozlog 03:31:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Running setup.py install for moznetwork 03:31:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Running setup.py install for mozprocess 03:31:28 INFO - Running setup.py install for mozprofile 03:31:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Running setup.py install for mozrunner 03:31:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Running setup.py install for mozscreenshot 03:31:28 INFO - Running setup.py install for moztest 03:31:28 INFO - Running setup.py install for mozversion 03:31:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:28 INFO - Cleaning up... 03:31:28 INFO - Return code: 0 03:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:28 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:28 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: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 - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 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': '/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 - '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - '_': '/tools/buildbot/bin/python'} 03:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:29 INFO - Running setup.py (path:/tmp/pip-BA5TZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:29 INFO - Running setup.py (path:/tmp/pip-Jdck4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:29 INFO - Running setup.py (path:/tmp/pip-EWUhHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:29 INFO - Running setup.py (path:/tmp/pip-6RJEgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:29 INFO - Running setup.py (path:/tmp/pip-sUWJ05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:29 INFO - Running setup.py (path:/tmp/pip-3nvzPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:29 INFO - Running setup.py (path:/tmp/pip-EX9E8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:29 INFO - Running setup.py (path:/tmp/pip-MuQFZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:29 INFO - Running setup.py (path:/tmp/pip-OqGhDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:29 INFO - Running setup.py (path:/tmp/pip-KVszg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:29 INFO - Running setup.py (path:/tmp/pip-L5zHfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:29 INFO - Running setup.py (path:/tmp/pip-JMapW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:29 INFO - Running setup.py (path:/tmp/pip-dukDev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:30 INFO - Running setup.py (path:/tmp/pip-Tj0sLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:30 INFO - Running setup.py (path:/tmp/pip-Og3QR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:30 INFO - Running setup.py (path:/tmp/pip-eSRMS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:30 INFO - Running setup.py (path:/tmp/pip-YpJkIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:30 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:30 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:30 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:30 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:30 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:30 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:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:30 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:30 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:30 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:30 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:30 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:30 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:32 INFO - Downloading blessings-1.6.tar.gz 03:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:32 INFO - Installing collected packages: blessings 03:31:32 INFO - Running setup.py install for blessings 03:31:32 INFO - Successfully installed blessings 03:31:32 INFO - Cleaning up... 03:31:32 INFO - Return code: 0 03:31:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:32 INFO - Reading from file tmpfile_stdout 03:31:32 INFO - Current package versions: 03:31:32 INFO - argparse == 1.2.1 03:31:32 INFO - blessings == 1.6 03:31:32 INFO - blobuploader == 1.2.4 03:31:32 INFO - docopt == 0.6.1 03:31:32 INFO - functools32 == 3.2.3-2 03:31:32 INFO - jsonschema == 2.5.1 03:31:32 INFO - manifestparser == 1.1 03:31:32 INFO - mozInstall == 1.12 03:31:32 INFO - mozcrash == 0.17 03:31:32 INFO - mozdebug == 0.1 03:31:32 INFO - mozdevice == 0.48 03:31:32 INFO - mozfile == 1.2 03:31:32 INFO - mozhttpd == 0.7 03:31:32 INFO - mozinfo == 0.9 03:31:32 INFO - mozleak == 0.1 03:31:32 INFO - mozlog == 3.2 03:31:32 INFO - moznetwork == 0.27 03:31:32 INFO - mozprocess == 0.22 03:31:32 INFO - mozprofile == 0.28 03:31:32 INFO - mozrunner == 6.11 03:31:32 INFO - mozscreenshot == 0.1 03:31:32 INFO - mozsystemmonitor == 0.3 03:31:32 INFO - moztest == 0.7 03:31:32 INFO - mozversion == 1.4 03:31:32 INFO - psutil == 3.1.1 03:31:32 INFO - requests == 1.2.3 03:31:32 INFO - wsgiref == 0.1.2 03:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:32 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 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:32 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:32 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:32 INFO - Using env: {'DISPLAY': ':0', 03:31:32 INFO - 'HOME': '/home/cltbld', 03:31:32 INFO - 'LANG': 'en_US.UTF-8', 03:31:32 INFO - 'LANGUAGE': 'en_US:en', 03:31:32 INFO - 'LOGNAME': 'cltbld', 03:31:32 INFO - 'MAIL': '/var/mail/cltbld', 03:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:32 INFO - 'MOZ_NO_REMOTE': '1', 03:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:32 INFO - 'NO_EM_RESTART': '1', 03:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:32 INFO - 'PWD': '/builds/slave/test', 03:31:32 INFO - 'SHELL': '/bin/bash', 03:31:32 INFO - 'SHLVL': '1', 03:31:32 INFO - 'TERM': 'linux', 03:31:32 INFO - 'TMOUT': '86400', 03:31:32 INFO - 'USER': 'cltbld', 03:31:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:32 INFO - '_': '/tools/buildbot/bin/python'} 03:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:33 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:33 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:33 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:33 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:33 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:33 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:33 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:33 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:33 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:33 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:33 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:33 INFO - Cleaning up... 03:31:33 INFO - Return code: 0 03:31:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:31:33 INFO - Using env: {'DISPLAY': ':0', 03:31:33 INFO - 'HOME': '/home/cltbld', 03:31:33 INFO - 'LANG': 'en_US.UTF-8', 03:31:33 INFO - 'LANGUAGE': 'en_US:en', 03:31:33 INFO - 'LOGNAME': 'cltbld', 03:31:33 INFO - 'MAIL': '/var/mail/cltbld', 03:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:33 INFO - 'MOZ_NO_REMOTE': '1', 03:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:33 INFO - 'NO_EM_RESTART': '1', 03:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:33 INFO - 'PWD': '/builds/slave/test', 03:31:33 INFO - 'SHELL': '/bin/bash', 03:31:33 INFO - 'SHLVL': '1', 03:31:33 INFO - 'TERM': 'linux', 03:31:33 INFO - 'TMOUT': '86400', 03:31:33 INFO - 'USER': 'cltbld', 03:31:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:33 INFO - '_': '/tools/buildbot/bin/python'} 03:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:31:33 INFO - Cleaning up... 03:31:33 INFO - Return code: 0 03:31:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd1af5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c50350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, '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': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', '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:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:31:33 INFO - Using env: {'DISPLAY': ':0', 03:31:33 INFO - 'HOME': '/home/cltbld', 03:31:33 INFO - 'LANG': 'en_US.UTF-8', 03:31:33 INFO - 'LANGUAGE': 'en_US:en', 03:31:33 INFO - 'LOGNAME': 'cltbld', 03:31:33 INFO - 'MAIL': '/var/mail/cltbld', 03:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:33 INFO - 'MOZ_NO_REMOTE': '1', 03:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:33 INFO - 'NO_EM_RESTART': '1', 03:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:33 INFO - 'PWD': '/builds/slave/test', 03:31:33 INFO - 'SHELL': '/bin/bash', 03:31:33 INFO - 'SHLVL': '1', 03:31:33 INFO - 'TERM': 'linux', 03:31:33 INFO - 'TMOUT': '86400', 03:31:33 INFO - 'USER': 'cltbld', 03:31:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:33 INFO - '_': '/tools/buildbot/bin/python'} 03:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:33 INFO - Downloading/unpacking flake8 03:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:31:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:31:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:31:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:36 INFO - no previously-included directories found matching 'docs/_build' 03:31:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:31:36 INFO - Running setup.py install for pep8 03:31:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:36 INFO - no previously-included directories found matching 'docs/_build' 03:31:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:31:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:31:36 INFO - Cleaning up... 03:31:36 INFO - Return code: 0 03:31:36 INFO - Running post-action listener: _resource_record_post_action 03:31:36 INFO - Running post-action listener: _start_resource_monitoring 03:31:36 INFO - Starting resource monitoring. 03:31:36 INFO - ##### 03:31:36 INFO - ##### Finished create-virtualenv step (success) 03:31:36 INFO - ##### 03:31:36 INFO - ##### 03:31:36 INFO - ##### Running install step. 03:31:36 INFO - ##### 03:31:36 INFO - Running pre-action listener: _resource_record_pre_action 03:31:36 INFO - Running main action method: install 03:31:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:36 INFO - Reading from file tmpfile_stdout 03:31:36 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:36 INFO - Reading from file tmpfile_stdout 03:31:36 INFO - Output received: 03:31:36 INFO - Usage: mozinstall [options] installer 03:31:36 INFO - Options: 03:31:36 INFO - -h, --help show this help message and exit 03:31:36 INFO - -d DEST, --destination=DEST 03:31:36 INFO - Directory to install application into. [default: 03:31:36 INFO - "/builds/slave/test"] 03:31:36 INFO - --app=APP Application being installed. [default: firefox] 03:31:36 INFO - mkdir: /builds/slave/test/build/application 03:31:36 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:36 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:52 INFO - Reading from file tmpfile_stdout 03:31:52 INFO - Output received: 03:31:52 INFO - /builds/slave/test/build/application/firefox/firefox 03:31:52 INFO - Running post-action listener: _resource_record_post_action 03:31:52 INFO - ##### 03:31:52 INFO - ##### Finished install step (success) 03:31:52 INFO - ##### 03:31:52 INFO - ##### 03:31:52 INFO - ##### Running run-tests step. 03:31:52 INFO - ##### 03:31:52 INFO - Running pre-action listener: _resource_record_pre_action 03:31:52 INFO - Running main action method: run_tests 03:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:52 INFO - Python 2.7.3 03:31:52 INFO - Return code: 0 03:31:52 INFO - grabbing minidump binary from tooltool 03:31: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:31:52 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 0x2cdf1c0>, '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 0x2ce00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce0550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:52 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:52 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:52 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:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:52 INFO - Return code: 0 03:31:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:31:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:31:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:31:53 INFO - Return code: 0 03:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:31:53 INFO - Using env: {'DISPLAY': ':0', 03:31:53 INFO - 'HOME': '/home/cltbld', 03:31:53 INFO - 'LANG': 'en_US.UTF-8', 03:31:53 INFO - 'LANGUAGE': 'en_US:en', 03:31:53 INFO - 'LOGNAME': 'cltbld', 03:31:53 INFO - 'MAIL': '/var/mail/cltbld', 03:31:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:53 INFO - 'MOZ_NO_REMOTE': '1', 03:31:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:53 INFO - 'NO_EM_RESTART': '1', 03:31:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:53 INFO - 'PWD': '/builds/slave/test', 03:31:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:53 INFO - 'SHELL': '/bin/bash', 03:31:53 INFO - 'SHLVL': '1', 03:31:53 INFO - 'TERM': 'linux', 03:31:53 INFO - 'TMOUT': '86400', 03:31:53 INFO - 'USER': 'cltbld', 03:31:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459', 03:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:53 INFO - '_': '/tools/buildbot/bin/python'} 03:31:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IiMFMX9EQxSo4QE2gcCdVg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:31:53 INFO - mozversion application_buildid: 20160627004013 03:31:53 INFO - mozversion application_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 03:31:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:31:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:53 INFO - mozversion application_name: Firefox 03:31:53 INFO - mozversion application_remotingname: firefox-dev 03:31:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:53 INFO - mozversion application_vendor: Mozilla 03:31:53 INFO - mozversion application_version: 49.0a2 03:31:53 INFO - mozversion platform_buildid: 20160627004013 03:31:53 INFO - mozversion platform_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 03:31:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:53 INFO - mozversion platform_version: 49.0a2 03:31:53 INFO - using testdate: 1467023513 03:31:53 INFO - actual date: 1467023513 03:31:53 INFO - starting webserver on 'localhost:40111' 03:31:53 INFO - SUITE-START | Running 2 tests 03:31:53 INFO - TEST-START | dromaeo_css 03:31:53 INFO - operating with platform_type : linux_ 03:31:53 INFO - Initialising browser for dromaeo_css test... 03:31:53 INFO - TEST-INFO | started process 483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpElmKFz/profile http://localhost:40111/getInfo.html) 03:31:57 INFO - PROCESS | 483 | __metrics Screen width/height:1600/1200 03:31:57 INFO - PROCESS | 483 | colorDepth:24 03:31:57 INFO - PROCESS | 483 | Browser inner width/height: 1024/697 03:31:57 INFO - PROCESS | 483 | __metrics 03:31:58 ERROR - PROCESS | 483 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:31:58 INFO - PROCESS | 483 | console.error: PushService: 03:31:58 INFO - PROCESS | 483 | stateChangeProcessEnqueue: Error transitioning state 03:31:58 INFO - PROCESS | 483 | UnknownError 03:31:59 INFO - TEST-INFO | 483: exit 0 03:31:59 INFO - Browser initialized. 03:31:59 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:59 INFO - TEST-INFO | started process 577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpElmKFz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:10 INFO - PROCESS | 577 | RSS: Main: 145399808 03:32:10 INFO - PROCESS | 577 | 03:33:02 INFO - PROCESS | 577 | 1467023582211 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:35:10 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40111/tests/dromaeo/cssquery-ext.html) 03:35:11 INFO - PROCESS | 577 | RSS: Main: 255447040 03:35:11 INFO - PROCESS | 577 | 03:38:13 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-ext.html (next: http://localhost:40111/tests/dromaeo/cssquery-jquery.html) 03:38:14 INFO - PROCESS | 577 | RSS: Main: 235737088 03:38:14 INFO - PROCESS | 577 | 03:41:15 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40111/tests/dromaeo/cssquery-mootools.html) 03:41:16 INFO - PROCESS | 577 | RSS: Main: 220487680 03:41:16 INFO - PROCESS | 577 | 03:44:18 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40111/tests/dromaeo/cssquery-prototype.html) 03:44:19 INFO - PROCESS | 577 | RSS: Main: 224587776 03:44:19 INFO - PROCESS | 577 | 03:47:21 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40111/tests/dromaeo/cssquery-yui.html) 03:47:22 INFO - PROCESS | 577 | RSS: Main: 218968064 03:47:22 INFO - PROCESS | 577 | 03:50:24 INFO - PROCESS | 577 | Cycle 1(1): loaded http://localhost:40111/tests/dromaeo/cssquery-yui.html (next: http://localhost:40111/tests/dromaeo/cssquery-dojo.html) 03:50:25 INFO - PROCESS | 577 | RSS: Main: 247619584 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | __start_tp_report 03:50:25 INFO - PROCESS | 577 | _x_x_mozilla_page_load 03:50:25 INFO - PROCESS | 577 | _x_x_mozilla_page_load_details 03:50:25 INFO - PROCESS | 577 | |i|pagename|runs| 03:50:25 INFO - PROCESS | 577 | |0;dojo.html;3373.241020770326;3384;3396.388650042992;3413.7269018478523;3436.467347561129;5484.91772623411;5517.062099443095;5571.359293681227;5575.916361254584;5593.74828132734;5512.862178445541;5550.77796888124;5563;5564.137558678402;5600.52395546379;4913.582345500637;5005.749712514369;5031.949680503203;5072.315237442935;5100.464451232623;4902.142125128106;5043.092243396187;5086.109930762121;5086.415062267831;5091.796328146888;5014.799408023675;5023.422306434771;5043.974780126095;5075.695458272516;5088.135017047092;5145.073886700392;5152.742362881841;5161.896762064775;5167.7932882684645;5190.844274671747;4921.876953076166;4922;4966.726830024347;5053.065182941167;5059.544640982293;5378.811741589026;5470.17947307903;5476.17857321401;5490.011797876381;5530.806421775219;5979.730912108922;5981.611195272337;6008.579399442041;6036.426539478743;6044.486218671418;5944.405559444064;6007.459328660441;6010.368911264298;6020.006698894721;6087.29996050451;5633.633813802131;5677.261955945701;5682.602217844753;5698.31620205578;5699.57253206007;6001.399860014051;6205.720742566596;6218;6219.968900155471;6222;5814.186013958049;5838.474537291664;5854.209681692917;5949.405059494103;5950.613210141327;5988.9101663475085;5989.221401217901;5990.5507086968655;6015.30823955242;6024.337322894478;5696.060150075191;5841.269841269841;5957.76168953237;5969.134475501028;5970.283565972111;5882.529397648178;5940.376260492586;5943.58394912352;5949.196858424144;5980.162777211194;4624;4631.166390049823;4632.583067523871;4634.86095417138;4659.510751371125;4032.0121570215483;4082.101937573729;4101;4113;4141.4409054777825;5753.8561535961935;5754.712264386764;5757.827265182052;5779;5800.738966746507;5914.497267732205;5970.134330522137;5978.521718262528;5982.341942386334;6000.219971403777;5584.553235741131;5659.943400566024;5673.375928647845;5685.147227915846;5753.165790960287;5202.739863006835;5205.55752761012;5206.0368831766245;5206.583473322125;5211.530962213419;6246.406591373812;6276.497880169575;6279.120923070774;6283.214598175228;6286;5825.417458254141;5913.940860591511;5956.731947062306;5974.40255974399;6016.8796624068145;6299.401556852092;6312.242530896322;6320.5575609706875;6323.715432805443;6334.6832658365975;5656.462636049569;5889.911651325147;5892.7642894283745;5896.469317761251;5929.0513517836935;5842.036064049555;5870.823875283749;5885.14665373527;5911.733971971185;5918.556108291981;5740.770369185186;5782.797602083907;5782.855428614318;5786.826395208151;5788.623739456922;5670.347909990404;5721.656700597978;5758.884822303614;5769.990251706018;5777.480026797609;5683.346415162309;5715.799947001835;5719.971400142973;5743.798967036134;5767.221425107557;5853.736581853742;5859.2382990211;5909.202257695156;5909.6158749681135;5915.70421478933;5533.391326953952;5653.038983372754;5699.772009119589;5761.971190144022;5781.595288329777 03:50:25 INFO - PROCESS | 577 | |1;ext.html;610.2463457629826;643.9742410303587;658.3844105761113;660.7621256347717;668.6556423441926;33804;46925.29612055822;48310.27534586984;48364.274535881945;48388.51611483893;49900.50099499013;50456.747716261365;50500;50560.49439505604;50598.24102638463;8597.140285971416;9084.682036128737;9305.209057230144;10818.891811081907;11125.388103654295;10926.508307126198;11036.503357348898;11045.28205666633;11045.558177672883;11048;10801.945990270038;10858.077063449617;10861.728456788565;10866.402347870864;10879.510422031028;8600.311975041981;11119.833202501968;11389.715257118556;11409.429528523624;11409.714757131138;10751;10834.349939003685;10846.078083362923;11194.608188713437;11208.439578021067;45661.771691141665;46260.306095408596;46317.30524042142;46478;46483.30275045877;3664.908377290589;3700.2414505026404;3724.0689827543115;3728.738988270822;4159.272127377697;35053.12367190815;35496.46755298672;35541.82229088864;35765;35951.82024089889;4213.389058586487;4227.302495088306;4321.416608757809;4348.152110338485;4387.100644367897;377371;380020;380251.0987445073;380373;382165;39998.80000599979;41063.58936410657;63811;64251.35748642547;64257;42448.57551424508;42751.78624106922;42921;42962.355564666555;42963.14073718508;15261.389544418322;16841.663166736842;16990;17008.914955425145;17018.744718829228;13549.119307244997;13745.931270343584;13765.38055787492;14126.64683382903;14252.073615215186;9398.624055037722;9518.619255229853;9519.048095190425;9567.952160239294;9572.377795443273;3624.3294990426857;3627.2926779277786;3640.162762564625;3699.8335074921156;3711.536057992751;1052.600011995437;1095.895889890459;1106.397013627575;1178.929264244131;1224.400043978444;34960.825195873855;35170.12074698102;35218.295634087684;35341.82329088338;35393.29213415717;1122.966311010671;1159.8260260960924;1219.2257916223261;1222.144498850808;1237.5915947737226;18232.36186733458;18267.72598411025;18350.724739128924;18635.906820466083;18640.72038919417;161595;163306;163885;163926;164124;66328;67828.6608566954;67837;67838.3216167832;67863;1331.5605850069453;1445.8915581331441;1458.4239225505867;1479.2012313350886;1504.5335945856818;34286.31427371489;35574.82212589024;35792.64207357893;36088.4586731194;36186;56667;56676.716616416656;57229;57386.13920791275;57426.7128664354;445833;447652;448029;448212;448378;28697.856510717313;32623.510647340783;33648.32703345871;33655.15862103467;33687.83156084302;52898.73550632222;53775.19337209867;54189;54202.45797542132;54817.1777423331;45193.774031130946;46366.30450543312;46660.76669616766;47014;47082.76458617685;45152;46029.53970460386;46054;46702.29946550736;47940.520594795;45658.54341456543;46559.53440465552;46622.53377466182;46762.766186168854;46975;39257.411138833515;40214.994625134605;40385.59614403936;41442.1711565761;41649.16701665889;29970.850145749133;30037.249068773457;30114.698853012065;30438.69561304447;30694.84652576723 03:50:25 INFO - PROCESS | 577 | |2;jquery.html;439.93400989851517;439.973601583905;440.60345688880017;444.85097492340066;449.5482040549247;18680.87914725116;19456.9027154864;20450.590988180247;20544.38366848997;20548.07533660985;18852;19056.428307150774;19657.115429805657;19725.50686232845;19745.70381444276;5712.22884910538;5866.266716660418;5881.529477641779;5893.675847828367;5906.11408288758;5690.146478028309;5709.6574205547595;5749.741261643215;5808.070708686611;5896.498797602209;5844.561657875676;5845.766169353221;5851.79518716845;5877.764889404419;5881.382454842244;5599.188117722928;5809.854753631151;5821.767129314801;5825.330087040003;5830.242068531085;5233.555147812441;5238.978399212155;5240.34495688039;5249.921251181236;5251.317328747276;21680;23389.298321050395;23689.7631023691;23709.88145059281;24560;2464.531738969608;2765.3777899972347;2781.123945957017;2796.2170592234206;3079.538069289602;14477.493287734984;14983;15010.324535395934;15230;15417.460388886335;2025.9696104558443;2030.4517780199265;2050.6001329740707;2145.5279838435517;2151.7633060363346;79468;80909.78635320475;82285.58857205676;82422.58788706078;84019.57990210071;10943.398113103696;11359;11541.942290288494;11589.24669896454;11701.718164957752;16270.26783794732;16306.347746090261;16319.592010199602;16335.673286534202;16364.099974501278;8845.69040083594;9030.593623286968;9125.269978401844;9168.40405373662;9182.403143795633;8721.694740684046;8744.69393571222;8844.071372505923;8880.467171969573;8968.506732129796;6951.478639102088;7007.824804379931;7117.964410177916;7209.2069872314005;7213.747518836815;1794.9155434226445;1955.4817973237105;2033.6136134134467;2081.6669332906663;2084.9944187529886;905.6694306578052;1055.8205105132147;1060.8408738689104;1067.4555976451911;1109.8612673415823;20045.298414555422;20155.596888062155;20159.798402016084;20495;20496.487587810425;1065.2862582069877;1073.018188357644;1080.189857606795;1098.780243951213;1109.7946879827257;11410.37242951629;11668.241564298294;12032.879671203176;12041.157119001586;12058.336791476377;366626;368904;474215;486327;488169;62091.689541552005;71481;103604.48197758963;105220.47389763156;106144;1032.4785983078498;1050.4800123938546;1091.1216470740972;1093.495894401811;1098.9890101098886;18193.727094093334;18207.726884096483;18314;18562.535936601693;18643.90678046601;46002.53997460116;46588.53411465842;46713;47071;48116.518834811206;64390.678046609464;64621.67689161524;66052;66088;66605;8892.466452012899;9125.45247285165;9128.49793261377;9241.630334786802;9613.711588652352;13448.529301474402;13588.388522516709;13757.38091785852;13817;14198;11887.583934562595;12141.392930353624;12597.87402125967;12643.620691379274;12680.74638507243;11830.231034982698;11867.406629668654;12085.335306558223;12091.274523528617;12137.878621214028;12093.274403535816;12208.32854193028;12459.626211213677;12581.559645412366;12632.305223212812;9242.630294788134;9411.670591529264;9414.952925235604;9415.917169525592;9544.624054722854;9535.809283814424;11563.42182890869;12620.936895315464;12801.487940482277;13124.737505250032 03:50:25 INFO - PROCESS | 577 | |3;mootools.html;1464.377639503211;1573.614464456208;1609.0989046134164;1835.85313174946;1951.3365455745043;9279.164875161234;9831.75420614485;9844.212463002952;10117.241206909475;10127.797444051133;9923.404595724262;10131.442770647629;10145.391276523394;10153.441560714158;10153.441560714158;2200.8569442986154;2273.863568185914;2496.1263557754824;2576.446064096224;2605.71337152913;2588.2752829207852;2618.1621880998086;2620.161548304534;2624.540705376561;2625.855577943212;2517.8363406378617;2581.935451613706;2583.3154214133174;2583.780378667806;2584.095566551711;2127.617028934791;2441.2676197140786;2499.3126890105277;2504.3613878460874;2522.1928982725535;2498.263012411343;2498.9875050624814;2517.8363406378526;2524.066095544642;2545.898164073435;10045.347052441568;10116.848247276297;10121.342112762646;10123.29136960413;10126.240531960133;5904.911426328608;6067.18093057436;6068.757249710006;6071.423214794588;6079.483243924272;3734.178480734234;3870.2259548090497;3883.514560679915;3889.766614003169;3921.901952451183;1221.8900298973047;1296.6239790460754;1314.625331780438;1345.9125156864773;1354.28223041788;14357.569272921724;14393.496227632088;14398.71202575953;14403.567892963229;14420.711585768226;5086.54221120101;5165.405978312542;5241.31862857826;5242.475752424727;5249.291345668362;11766.294022358687;11815.05479561633;11816.527338906348;11825.940870295593;11827.231229970024;5929.940700592939;5953.791617293374;5957.4042595740075;5958.076498142795;5970.641761494324;4593.28804035375;5355;5521.668699878021;5525.254090697785;5613.663180209119;4471.7987690554;4504.459464864237;4504.977475112603;4542.704724192917;4544.431946006749;1373.7527245095978;1383.3083458270864;1400.1179257068186;1407.7817938219591;1411.7600007998665;1004.8191325561323;1053.89461053894;1057.434272664121;1071.5499490214129;1090.9890901090887;6442.35576442352;6669.966650166718;6754.932450675528;6774.559653622598;6839.863202735917;489.8456986049383;490.43109992408637;495.5391485918059;498.7680728461283;506.0839879817542;4413.492448368414;4474.836542498973;4475.888102797456;4481.619062379734;4508.278675411918;12390.256584604953;12760.042996775093;12882.484700612058;12892;12903.935480322538;12575.87424125747;12648.936755316165;12650.987920966485;12662;12663.556775512812;8753.912460875437;9102.772430689154;9115;9115.316351273681;9116.908830911738;6277.71750271248;6453.515986300952;6539.378759017885;6550.672466376757;6557.540972132096;11074.557017719524;11138.164637652046;11192.328460292409;11253.268537545033;11356;11343.376114313462;11562.76874462523;11648.184627076023;11662.241954272946;11675.708107297385;7565.319121279112;8576.099509551408;8809.515476648847;8812.91187088121;8978.820423591622;9876.111149996535;9882.950585247028;9966.302358834811;9980.051895069957;9985.350952188375;8668.696595619123;8845.513496757483;8909;8972.596233169654;9042.276617870422;8288.461250018729;8300.916990830256;8335.666573337;8457.957710211409;8476.152384761475;7907.8813817793925;7955.641996110305;7996.960015199887;8021.037475502977;8022.518648881086;5582.134769110794;5688.260526131577;5733.71331433435;5851.795187168429;5880.26496687914;5494.587905906993;5588.524975377139;5691.8292451226525;5715.257016587817;5752.194692742935 03:50:25 INFO - PROCESS | 577 | |4;prototype.html;360.6934106009891;363.351417719371;364.96715295623386;369.8834867016889;373.1175769305593;5364.356277246726;5404.135338345865;5486.286782718246;5531.972340138299;5558.444155584434;5378.946210537893;5406.40529541751;5413.485718856702;5459.590530710203;5468.9179662305;4115.773632450214;4158.916821663565;4284.4216030835905;4311.331743579734;4327.199468098412;4270.444842170514;4281.72168809028;4307.504636966758;4309.870703878889;4324.329728892011;4259.424977628011;4264.893377665552;4276.315789473685;4283.100548884738;4284.400183974246;4026.4966879140106;4260.829566817323;4263.339182426729;4268.103698223377;4269.978650106761;3770.1894092769912;3772.5095737554216;3774.8109345556063;3781.357169281201;3789.5073640426845;5278.1818818082875;5281.683099014072;5296;5316.6544174628525;5326.334208223972;4761.904761904777;4919.1391506486225;4955.925661115086;4957.454679985199;4963.081829861487;5612.831615051514;5636.843367714093;5679.488846003879;5715.428457154293;5789.392113828029;4139.544650088488;4154.231467178552;4161.854335098287;4169.791510424467;4179.958200417987;5779.9422005779825;5888.439563515672;5923.496502797268;5966.164736936789;5966.463018328372;4217.768022758778;4221.936670949938;4229.323308270662;4250.149970006011;4258.423653689535;4283.657307415399;4289.099289149313;4314.784260786948;4353.25994580922;4353.847615333448;4640.793393717658;4754.619630429557;4826.638002149853;4873.244647079709;4953.2074868020945;5565.693886836263;5667.943320566742;5690.5447564194765;5691.288588926384;5695.231143795535;1459.4599998000808;1463.1952426165567;1466.5746933389412;1472.7569950957973;1474.71243107593;922.4787994782927;942.2462030375672;959.203860795538;959.5921733263335;959.8800149981253;4994.500549945049;5009.371954114928;5017.8243761468175;5033.84898453047;5061.645684802103;5158.845234642967;5159.974200129051;5166.13279629809;5180.274761533388;5183.792648294026;5011.774470148852;5033.949660503421;5060.569851562658;5203.661761985459;5637.605367624227;3083.953740693891;3098.62816462026;3121.531770234483;3125.92155706281;3126.3434678717267;6119.388061193845;6127.863692007877;6132.50939924805;6175.722092505848;6178.876422471525;5758.712064396764;6049.5462840287155;6056.788012419544;6067.302260240041;6080.847978800477;6170.413810687977;6171.783987560414;6192.164057852222;6215.875682486235;6236.532260080512;5649.0907182800065;5877;5884.382139875252;5932.911006334822;5942.910856337072;5811.680357580204;5828.358880523054;5849.532037436909;5852.239208902815;5896.292444906639;5785.82642520725;6186.257649082139;6204.3795620437595;6224;6241.407066328691;4266.146770645945;4307.483102027777;4342.1532801103485;4353.542877997765;4367.519572846921;5650.29371328584;5780.682062486603;5784.334801497882;5797.449242321973;5840.182374467493;5724.914126288026;5752.68360240174;5762.106873434456;5779.8555036124435;5794.739236734273;5640.605157638925;5687.345955215038;5708.143778433268;5721.885562288814;5735.738137609719;5571.832845014656;5576.972115139399;5628.352739435018;5648.237487939075;5692.886142277214;5737.741801618854;5757.453041961007;5762.337331206797;5769.163471296554;5781.884362312814;5435.23906653061;5621.437856214346;5629.986602411605;5657;5672.290963629546 03:50:25 INFO - PROCESS | 577 | |5;yui.html;741.125471943107;790.6521130702487;792.9572612015199;802.8033131882688;844.9704260350888;15700.450484233053;16998.150092495358;17040.829591704078;17104.05927673979;17108.657826843457;13079.411426485807;15614.141222232773;15763.84236157638;15796;15826.28781704823;853.9402241843073;880.2561835249236;888.080836334394;890.3011136258039;890.4657205676607;880.3705350674267;885.0883589902379;887.2369762004671;888.3293113699181;888.4402826219473;753.9849203015937;910.6493999810054;913.6117150211167;924.2375040591505;926.5182105305271;899.5322432335182;906.2251774732574;906.3746015249457;907.6823111910844;907.8728977943093;671.392389887153;672.4553111979312;673.061079793685;673.1383828699272;673.1719984419143;15428.074315541104;15445;15481.845181548153;15489.380424782996;15493.845061549353;15327.080375177413;15560.533184004498;15613.921930390388;15637.530874073796;15815.762763558556;13399.866001339959;13625.523106691218;13725.176489410567;13751.449942002308;13814.65463363414;4708.529147085298;4743.051389722069;4781.330613714083;4782.354382158399;4890.193118135503;80274;80363.59818200872;82503;84679.15320846775;84762.57618711867;12243.081768867192;12251.387430628432;12294.938525307316;12369.752604947851;12643.178193417398;23865.7613423867;23932.7606723934;23936.7606323938;23941.7605823943;23953.640695389582;8685.91314086851;8714.215720585178;8764.605592748341;8845.292376609856;8985.236254918405;4503.662225333113;4612.262038073959;4670.719756814602;4892.608591312686;4900.509949005071;3596.9280614387567;3697.2975134724315;3708.8331025103394;3711.0722319420147;3713;587.3040447070235;661.0711949710665;690.5890994858051;690.5960013392122;693.5041445366587;606.3088079589272;664.3788058165534;726.7565365602583;733.3729661139755;794.7615715285391;10669.573217071385;10725.677237575072;10772.407517586607;10954.233203675667;10961.890381096246;636.4303947966637;697.3793323941696;718.9856202876018;732.0848938826467;747.8466914282559;5063.645544811901;5096.286519887219;5166.870828229325;5173.71544565045;5325.227841962894;75467.24532754603;107235.4638226804;124961.37519312528;128814;129956;89594;91350;99552.50223748836;100998;104985.47507262568;87793;90558;93559;96308;97835.51082244686;10501.842472363074;10543.527570202785;10543.78912421732;10655.094316983143;10869.565217391531;48520.02959940852;52115.21827172671;54964.45035549593;55844.72077639586;55916;67998.32001680118;68220;69377;69582;69702.30297696959;12782.296973666538;13465.192088474723;13673.384697688429;13746.312684365541;13772.31138443094;15409.075455472708;15554.611134721723;15611.141387223812;15623.765643515584;15883.444079457164;9967.750806229902;13642.113262637897;14036.228007459687;14199.148051116967;14920.403183872524;14063.578092657237;14162.079464834753;14324;14392.496262630757;14572.708545828811;13748.793768093687;13939.233342165873;14356.928215358856;14420.351084201026;14422.711545768816;10635.627753028606;10811.189160812813;11077.944610276896;12049.578264760692;12186;11732.882671173178;11868.643940681794;11941.82087268709;12078.335691536699;12195.51217951272 03:50:25 INFO - PROCESS | 577 | __end_tp_report 03:50:25 INFO - PROCESS | 577 | __start_cc_report 03:50:25 INFO - PROCESS | 577 | _x_x_mozilla_cycle_collect,859 03:50:25 INFO - PROCESS | 577 | __end_cc_report 03:50:25 INFO - PROCESS | 577 | __startTimestamp1467024625032__endTimestamp 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | ------- Summary: start ------- 03:50:25 INFO - PROCESS | 577 | Number of tests: 6 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#0] dojo.html Cycles:165 Average:5553.28 Median:5753.86 stddev:614.87 (10.7%) stddev-sans-first:592.50 03:50:25 INFO - PROCESS | 577 | Values: 3373.2 3384.0 3396.4 3413.7 3436.5 5484.9 5517.1 5571.4 5575.9 5593.7 5512.9 5550.8 5563.0 5564.1 5600.5 4913.6 5005.7 5031.9 5072.3 5100.5 4902.1 5043.1 5086.1 5086.4 5091.8 5014.8 5023.4 5044.0 5075.7 5088.1 5145.1 5152.7 5161.9 5167.8 5190.8 4921.9 4922.0 4966.7 5053.1 5059.5 5378.8 5470.2 5476.2 5490.0 5530.8 5979.7 5981.6 6008.6 6036.4 6044.5 5944.4 6007.5 6010.4 6020.0 6087.3 5633.6 5677.3 5682.6 5698.3 5699.6 6001.4 6205.7 6218.0 6220.0 6222.0 5814.2 5838.5 5854.2 5949.4 5950.6 5988.9 5989.2 5990.6 6015.3 6024.3 5696.1 5841.3 5957.8 5969.1 5970.3 5882.5 5940.4 5943.6 5949.2 5980.2 4624.0 4631.2 4632.6 4634.9 4659.5 4032.0 4082.1 4101.0 4113.0 4141.4 5753.9 5754.7 5757.8 5779.0 5800.7 5914.5 5970.1 5978.5 5982.3 6000.2 5584.6 5659.9 5673.4 5685.1 5753.2 5202.7 5205.6 5206.0 5206.6 5211.5 6246.4 6276.5 6279.1 6283.2 6286.0 5825.4 5913.9 5956.7 5974.4 6016.9 6299.4 6312.2 6320.6 6323.7 6334.7 5656.5 5889.9 5892.8 5896.5 5929.1 5842.0 5870.8 5885.1 5911.7 5918.6 5740.8 5782.8 5782.9 5786.8 5788.6 5670.3 5721.7 5758.9 5770.0 5777.5 5683.3 5715.8 5720.0 5743.8 5767.2 5853.7 5859.2 5909.2 5909.6 5915.7 5533.4 5653.0 5699.8 5762.0 5781.6 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#1] ext.html Cycles:180 Average:52387.90 Median:190549.87 stddev:93132.31 (48.9%) stddev-sans-first:93312.43 03:50:25 INFO - PROCESS | 577 | Values: 610.2 644.0 658.4 660.8 668.7 33804.0 46925.3 48310.3 48364.3 48388.5 49900.5 50456.7 50500.0 50560.5 50598.2 8597.1 9084.7 9305.2 10818.9 11125.4 10926.5 11036.5 11045.3 11045.6 11048.0 10801.9 10858.1 10861.7 10866.4 10879.5 8600.3 11119.8 11389.7 11409.4 11409.7 10751.0 10834.3 10846.1 11194.6 11208.4 45661.8 46260.3 46317.3 46478.0 46483.3 3664.9 3700.2 3724.1 3728.7 4159.3 35053.1 35496.5 35541.8 35765.0 35951.8 4213.4 4227.3 4321.4 4348.2 4387.1 377371.0 380020.0 380251.1 380373.0 382165.0 39998.8 41063.6 63811.0 64251.4 64257.0 42448.6 42751.8 42921.0 42962.4 42963.1 15261.4 16841.7 16990.0 17008.9 17018.7 13549.1 13745.9 13765.4 14126.6 14252.1 9398.6 9518.6 9519.0 9568.0 9572.4 3624.3 3627.3 3640.2 3699.8 3711.5 1052.6 1095.9 1106.4 1178.9 1224.4 34960.8 35170.1 35218.3 35341.8 35393.3 1123.0 1159.8 1219.2 1222.1 1237.6 18232.4 18267.7 18350.7 18635.9 18640.7 161595.0 163306.0 163885.0 163926.0 164124.0 66328.0 67828.7 67837.0 67838.3 67863.0 1331.6 1445.9 1458.4 1479.2 1504.5 34286.3 35574.8 35792.6 36088.5 36186.0 56667.0 56676.7 57229.0 57386.1 57426.7 445833.0 447652.0 448029.0 448212.0 448378.0 28697.9 32623.5 33648.3 33655.2 33687.8 52898.7 53775.2 54189.0 54202.5 54817.2 45193.8 46366.3 46660.8 47014.0 47082.8 45152.0 46029.5 46054.0 46702.3 47940.5 45658.5 46559.5 46622.5 46762.8 46975.0 39257.4 40215.0 40385.6 41442.2 41649.2 29970.9 30037.2 30114.7 30438.7 30694.8 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#2] jquery.html Cycles:180 Average:28386.79 Median:11915.88 stddev:73008.23 (612.7%) stddev-sans-first:73182.88 03:50:25 INFO - PROCESS | 577 | Values: 439.9 440.0 440.6 444.9 449.5 18680.9 19456.9 20450.6 20544.4 20548.1 18852.0 19056.4 19657.1 19725.5 19745.7 5712.2 5866.3 5881.5 5893.7 5906.1 5690.1 5709.7 5749.7 5808.1 5896.5 5844.6 5845.8 5851.8 5877.8 5881.4 5599.2 5809.9 5821.8 5825.3 5830.2 5233.6 5239.0 5240.3 5249.9 5251.3 21680.0 23389.3 23689.8 23709.9 24560.0 2464.5 2765.4 2781.1 2796.2 3079.5 14477.5 14983.0 15010.3 15230.0 15417.5 2026.0 2030.5 2050.6 2145.5 2151.8 79468.0 80909.8 82285.6 82422.6 84019.6 10943.4 11359.0 11541.9 11589.2 11701.7 16270.3 16306.3 16319.6 16335.7 16364.1 8845.7 9030.6 9125.3 9168.4 9182.4 8721.7 8744.7 8844.1 8880.5 8968.5 6951.5 7007.8 7118.0 7209.2 7213.7 1794.9 1955.5 2033.6 2081.7 2085.0 905.7 1055.8 1060.8 1067.5 1109.9 20045.3 20155.6 20159.8 20495.0 20496.5 1065.3 1073.0 1080.2 1098.8 1109.8 11410.4 11668.2 12032.9 12041.2 12058.3 366626.0 368904.0 474215.0 486327.0 488169.0 62091.7 71481.0 103604.5 105220.5 106144.0 1032.5 1050.5 1091.1 1093.5 1099.0 18193.7 18207.7 18314.0 18562.5 18643.9 46002.5 46588.5 46713.0 47071.0 48116.5 64390.7 64621.7 66052.0 66088.0 66605.0 8892.5 9125.5 9128.5 9241.6 9613.7 13448.5 13588.4 13757.4 13817.0 14198.0 11887.6 12141.4 12597.9 12643.6 12680.7 11830.2 11867.4 12085.3 12091.3 12137.9 12093.3 12208.3 12459.6 12581.6 12632.3 9242.6 9411.7 9415.0 9415.9 9544.6 9535.8 11563.4 12620.9 12801.5 13124.7 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#3] mootools.html Cycles:180 Average:6545.12 Median:4167.70 stddev:3842.38 (92.2%) stddev-sans-first:3834.19 03:50:25 INFO - PROCESS | 577 | Values: 1464.4 1573.6 1609.1 1835.9 1951.3 9279.2 9831.8 9844.2 10117.2 10127.8 9923.4 10131.4 10145.4 10153.4 10153.4 2200.9 2273.9 2496.1 2576.4 2605.7 2588.3 2618.2 2620.2 2624.5 2625.9 2517.8 2581.9 2583.3 2583.8 2584.1 2127.6 2441.3 2499.3 2504.4 2522.2 2498.3 2499.0 2517.8 2524.1 2545.9 10045.3 10116.8 10121.3 10123.3 10126.2 5904.9 6067.2 6068.8 6071.4 6079.5 3734.2 3870.2 3883.5 3889.8 3921.9 1221.9 1296.6 1314.6 1345.9 1354.3 14357.6 14393.5 14398.7 14403.6 14420.7 5086.5 5165.4 5241.3 5242.5 5249.3 11766.3 11815.1 11816.5 11825.9 11827.2 5929.9 5953.8 5957.4 5958.1 5970.6 4593.3 5355.0 5521.7 5525.3 5613.7 4471.8 4504.5 4505.0 4542.7 4544.4 1373.8 1383.3 1400.1 1407.8 1411.8 1004.8 1053.9 1057.4 1071.5 1091.0 6442.4 6670.0 6754.9 6774.6 6839.9 489.8 490.4 495.5 498.8 506.1 4413.5 4474.8 4475.9 4481.6 4508.3 12390.3 12760.0 12882.5 12892.0 12903.9 12575.9 12648.9 12651.0 12662.0 12663.6 8753.9 9102.8 9115.0 9115.3 9116.9 6277.7 6453.5 6539.4 6550.7 6557.5 11074.6 11138.2 11192.3 11253.3 11356.0 11343.4 11562.8 11648.2 11662.2 11675.7 7565.3 8576.1 8809.5 8812.9 8978.8 9876.1 9883.0 9966.3 9980.1 9985.4 8668.7 8845.5 8909.0 8972.6 9042.3 8288.5 8300.9 8335.7 8458.0 8476.2 7907.9 7955.6 7997.0 8021.0 8022.5 5582.1 5688.3 5733.7 5851.8 5880.3 5494.6 5588.5 5691.8 5715.3 5752.2 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#4] prototype.html Cycles:180 Average:4797.14 Median:5391.54 stddev:1401.80 (26.0%) stddev-sans-first:1365.61 03:50:25 INFO - PROCESS | 577 | Values: 360.7 363.4 365.0 369.9 373.1 5364.4 5404.1 5486.3 5532.0 5558.4 5378.9 5406.4 5413.5 5459.6 5468.9 4115.8 4158.9 4284.4 4311.3 4327.2 4270.4 4281.7 4307.5 4309.9 4324.3 4259.4 4264.9 4276.3 4283.1 4284.4 4026.5 4260.8 4263.3 4268.1 4270.0 3770.2 3772.5 3774.8 3781.4 3789.5 5278.2 5281.7 5296.0 5316.7 5326.3 4761.9 4919.1 4955.9 4957.5 4963.1 5612.8 5636.8 5679.5 5715.4 5789.4 4139.5 4154.2 4161.9 4169.8 4180.0 5779.9 5888.4 5923.5 5966.2 5966.5 4217.8 4221.9 4229.3 4250.1 4258.4 4283.7 4289.1 4314.8 4353.3 4353.8 4640.8 4754.6 4826.6 4873.2 4953.2 5565.7 5667.9 5690.5 5691.3 5695.2 1459.5 1463.2 1466.6 1472.8 1474.7 922.5 942.2 959.2 959.6 959.9 4994.5 5009.4 5017.8 5033.8 5061.6 5158.8 5160.0 5166.1 5180.3 5183.8 5011.8 5033.9 5060.6 5203.7 5637.6 3084.0 3098.6 3121.5 3125.9 3126.3 6119.4 6127.9 6132.5 6175.7 6178.9 5758.7 6049.5 6056.8 6067.3 6080.8 6170.4 6171.8 6192.2 6215.9 6236.5 5649.1 5877.0 5884.4 5932.9 5942.9 5811.7 5828.4 5849.5 5852.2 5896.3 5785.8 6186.3 6204.4 6224.0 6241.4 4266.1 4307.5 4342.2 4353.5 4367.5 5650.3 5780.7 5784.3 5797.4 5840.2 5724.9 5752.7 5762.1 5779.9 5794.7 5640.6 5687.3 5708.1 5721.9 5735.7 5571.8 5577.0 5628.4 5648.2 5692.9 5737.7 5757.5 5762.3 5769.2 5781.9 5435.2 5621.4 5630.0 5657.0 5672.3 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - PROCESS | 577 | [#5] yui.html Cycles:180 Average:21436.50 Median:4557.96 stddev:30337.79 (665.6%) stddev-sans-first:30383.09 03:50:25 INFO - PROCESS | 577 | Values: 741.1 790.7 793.0 802.8 845.0 15700.5 16998.2 17040.8 17104.1 17108.7 13079.4 15614.1 15763.8 15796.0 15826.3 853.9 880.3 888.1 890.3 890.5 880.4 885.1 887.2 888.3 888.4 754.0 910.6 913.6 924.2 926.5 899.5 906.2 906.4 907.7 907.9 671.4 672.5 673.1 673.1 673.2 15428.1 15445.0 15481.8 15489.4 15493.8 15327.1 15560.5 15613.9 15637.5 15815.8 13399.9 13625.5 13725.2 13751.4 13814.7 4708.5 4743.1 4781.3 4782.4 4890.2 80274.0 80363.6 82503.0 84679.2 84762.6 12243.1 12251.4 12294.9 12369.8 12643.2 23865.8 23932.8 23936.8 23941.8 23953.6 8685.9 8714.2 8764.6 8845.3 8985.2 4503.7 4612.3 4670.7 4892.6 4900.5 3596.9 3697.3 3708.8 3711.1 3713.0 587.3 661.1 690.6 690.6 693.5 606.3 664.4 726.8 733.4 794.8 10669.6 10725.7 10772.4 10954.2 10961.9 636.4 697.4 719.0 732.1 747.8 5063.6 5096.3 5166.9 5173.7 5325.2 75467.2 107235.5 124961.4 128814.0 129956.0 89594.0 91350.0 99552.5 100998.0 104985.5 87793.0 90558.0 93559.0 96308.0 97835.5 10501.8 10543.5 10543.8 10655.1 10869.6 48520.0 52115.2 54964.5 55844.7 55916.0 67998.3 68220.0 69377.0 69582.0 69702.3 12782.3 13465.2 13673.4 13746.3 13772.3 15409.1 15554.6 15611.1 15623.8 15883.4 9967.8 13642.1 14036.2 14199.1 14920.4 14063.6 14162.1 14324.0 14392.5 14572.7 13748.8 13939.2 14356.9 14420.4 14422.7 10635.6 10811.2 11077.9 12049.6 12186.0 11732.9 11868.6 11941.8 12078.3 12195.5 03:50:25 INFO - PROCESS | 577 | -------- Summary: end -------- 03:50:25 INFO - PROCESS | 577 | 03:50:25 INFO - TEST-INFO | 577: exit 0 03:50:25 INFO - TEST-OK | dromaeo_css | took 1111892ms 03:50:25 INFO - TEST-START | kraken 03:50:25 INFO - operating with platform_type : linux_ 03:50:25 INFO - Initialising browser for kraken test... 03:50:25 INFO - TEST-INFO | started process 773 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt6RpZ9/profile http://localhost:40111/getInfo.html) 03:50:29 INFO - PROCESS | 773 | __metrics Screen width/height:1600/1200 03:50:29 INFO - PROCESS | 773 | colorDepth:24 03:50:29 INFO - PROCESS | 773 | Browser inner width/height: 1024/697 03:50:29 INFO - PROCESS | 773 | __metrics 03:50:30 ERROR - PROCESS | 773 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:50:30 INFO - PROCESS | 773 | console.error: PushService: 03:50:30 INFO - PROCESS | 773 | stateChangeProcessEnqueue: Error transitioning state 03:50:30 INFO - PROCESS | 773 | UnknownError 03:50:30 INFO - TEST-INFO | 773: exit 0 03:50:30 INFO - Browser initialized. 03:50:30 INFO - Running cycle 1/1 for kraken test... 03:50:30 INFO - TEST-INFO | started process 870 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt6RpZ9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:42 INFO - PROCESS | 870 | RSS: Main: 149753856 03:50:42 INFO - PROCESS | 870 | 03:50:45 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40111/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:46 INFO - PROCESS | 870 | RSS: Main: 198008832 03:50:46 INFO - PROCESS | 870 | 03:50:48 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40111/tests/kraken/driver.html?testName=audio-dft) 03:50:49 INFO - PROCESS | 870 | RSS: Main: 197357568 03:50:49 INFO - PROCESS | 870 | 03:50:52 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40111/tests/kraken/driver.html?testName=audio-fft) 03:50:52 INFO - PROCESS | 870 | RSS: Main: 282910720 03:50:52 INFO - PROCESS | 870 | 03:50:54 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40111/tests/kraken/driver.html?testName=audio-oscillator) 03:50:55 INFO - PROCESS | 870 | RSS: Main: 191287296 03:50:55 INFO - PROCESS | 870 | 03:50:57 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40111/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:50:58 INFO - PROCESS | 870 | RSS: Main: 192434176 03:50:58 INFO - PROCESS | 870 | 03:51:01 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40111/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:01 INFO - PROCESS | 870 | RSS: Main: 226971648 03:51:01 INFO - PROCESS | 870 | 03:51:05 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40111/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:05 INFO - PROCESS | 870 | RSS: Main: 227540992 03:51:05 INFO - PROCESS | 870 | 03:51:08 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40111/tests/kraken/driver.html?testName=json-parse-financial) 03:51:09 INFO - PROCESS | 870 | RSS: Main: 227373056 03:51:09 INFO - PROCESS | 870 | 03:51:10 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40111/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:11 INFO - PROCESS | 870 | RSS: Main: 204410880 03:51:11 INFO - PROCESS | 870 | 03:51:12 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:13 INFO - PROCESS | 870 | RSS: Main: 206413824 03:51:13 INFO - PROCESS | 870 | 03:51:15 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:16 INFO - PROCESS | 870 | RSS: Main: 209309696 03:51:16 INFO - PROCESS | 870 | 03:51:18 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:19 INFO - PROCESS | 870 | RSS: Main: 214695936 03:51:19 INFO - PROCESS | 870 | 03:51:21 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:22 INFO - PROCESS | 870 | RSS: Main: 213766144 03:51:22 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | Cycle 1(1): loaded http://localhost:40111/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40111/tests/kraken/driver.html?testName=ai-astar) 03:51:24 INFO - PROCESS | 870 | RSS: Main: 256532480 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | __start_tp_report 03:51:24 INFO - PROCESS | 870 | _x_x_mozilla_page_load 03:51:24 INFO - PROCESS | 870 | _x_x_mozilla_page_load_details 03:51:24 INFO - PROCESS | 870 | |i|pagename|runs| 03:51:24 INFO - PROCESS | 870 | |0;ai-astar;112;102;110;109;111;110;110;110;109;109 03:51:24 INFO - PROCESS | 870 | |1;audio-beat-detection;157;134;126;174;126;125;148;127;166;134 03:51:24 INFO - PROCESS | 870 | |2;audio-dft;161;163;161;163;162;162;160;160;161;162 03:51:24 INFO - PROCESS | 870 | |3;audio-fft;75;76;75;127;87;77;75;76;99;77 03:51:24 INFO - PROCESS | 870 | |4;audio-oscillator;85;85;121;85;84;84;114;84;85;122 03:51:24 INFO - PROCESS | 870 | |5;imaging-gaussian-blur;117;118;116;117;116;117;117;117;117;117 03:51:24 INFO - PROCESS | 870 | |6;imaging-darkroom;146;146;146;145;146;146;146;147;146;146 03:51:24 INFO - PROCESS | 870 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;96;94 03:51:24 INFO - PROCESS | 870 | |8;json-parse-financial;49;50;49;50;52;50;53;50;52;52 03:51:24 INFO - PROCESS | 870 | |9;json-stringify-tinderbox;61;59;59;59;61;61;60;60;60;60 03:51:24 INFO - PROCESS | 870 | |10;stanford-crypto-aes;77;76;76;76;79;77;78;77;106;77 03:51:24 INFO - PROCESS | 870 | |11;stanford-crypto-ccm;125;122;134;117;116;120;132;120;118;126 03:51:24 INFO - PROCESS | 870 | |12;stanford-crypto-pbkdf2;156;154;155;154;163;161;157;156;154;152 03:51:24 INFO - PROCESS | 870 | |13;stanford-crypto-sha256-iterative;60;61;63;64;61;63;61;60;61;60 03:51:24 INFO - PROCESS | 870 | __end_tp_report 03:51:24 INFO - PROCESS | 870 | __start_cc_report 03:51:24 INFO - PROCESS | 870 | _x_x_mozilla_cycle_collect,1558 03:51:24 INFO - PROCESS | 870 | __end_cc_report 03:51:24 INFO - PROCESS | 870 | __startTimestamp1467024684318__endTimestamp 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | ------- Summary: start ------- 03:51:24 INFO - PROCESS | 870 | Number of tests: 14 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:2.70 (2.5%) stddev-sans-first:2.67 03:51:24 INFO - PROCESS | 870 | Values: 112.0 102.0 110.0 109.0 111.0 110.0 110.0 110.0 109.0 109.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:141.00 stddev:18.30 (13.0%) stddev-sans-first:18.55 03:51:24 INFO - PROCESS | 870 | Values: 157.0 134.0 126.0 174.0 126.0 125.0 148.0 127.0 166.0 134.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 03:51:24 INFO - PROCESS | 870 | Values: 161.0 163.0 161.0 163.0 162.0 162.0 160.0 160.0 161.0 162.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#3] audio-fft Cycles:10 Average:84.40 Median:76.50 stddev:16.83 (22.0%) stddev-sans-first:17.51 03:51:24 INFO - PROCESS | 870 | Values: 75.0 76.0 75.0 127.0 87.0 77.0 75.0 76.0 99.0 77.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.50 stddev:16.76 (19.8%) stddev-sans-first:17.39 03:51:24 INFO - PROCESS | 870 | Values: 85.0 85.0 121.0 85.0 84.0 84.0 114.0 84.0 85.0 122.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 03:51:24 INFO - PROCESS | 870 | Values: 117.0 118.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 03:51:24 INFO - PROCESS | 870 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 147.0 146.0 146.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:51:24 INFO - PROCESS | 870 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 94.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.42 (2.8%) stddev-sans-first:1.36 03:51:24 INFO - PROCESS | 870 | Values: 49.0 50.0 49.0 50.0 52.0 50.0 53.0 50.0 52.0 52.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.78 03:51:24 INFO - PROCESS | 870 | Values: 61.0 59.0 59.0 59.0 61.0 61.0 60.0 60.0 60.0 60.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:77.00 stddev:9.22 (12.0%) stddev-sans-first:9.72 03:51:24 INFO - PROCESS | 870 | Values: 77.0 76.0 76.0 76.0 79.0 77.0 78.0 77.0 106.0 77.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.50 stddev:6.18 (5.0%) stddev-sans-first:6.51 03:51:24 INFO - PROCESS | 870 | Values: 125.0 122.0 134.0 117.0 116.0 120.0 132.0 120.0 118.0 126.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:156.00 stddev:3.39 (2.2%) stddev-sans-first:3.60 03:51:24 INFO - PROCESS | 870 | Values: 156.0 154.0 155.0 154.0 163.0 161.0 157.0 156.0 154.0 152.0 03:51:24 INFO - PROCESS | 870 | 03:51:24 INFO - PROCESS | 870 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.00 stddev:1.43 (2.3%) stddev-sans-first:1.42 03:51:24 INFO - PROCESS | 870 | Values: 60.0 61.0 63.0 64.0 61.0 63.0 61.0 60.0 61.0 60.0 03:51:24 INFO - PROCESS | 870 | -------- Summary: end -------- 03:51:24 INFO - PROCESS | 870 | 03:51:25 INFO - TEST-INFO | 870: exit 0 03:51:25 INFO - TEST-OK | kraken | took 59647ms 03:51:25 INFO - SUITE-END | took 1171s 03:51:25 INFO - Completed test suite (00:19:31) 03:51:25 INFO - javascript benchmark 03:51:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3373.241020770326, 3384.0, 3396.388650042992, 3413.7269018478523, 3436.467347561129, 5484.91772623411, 5517.062099443095, 5571.359293681227, 5575.916361254584, 5593.74828132734, 5512.862178445541, 5550.77796888124, 5563.0, 5564.137558678402, 5600.52395546379, 4913.582345500637, 5005.749712514369, 5031.949680503203, 5072.315237442935, 5100.464451232623, 4902.142125128106, 5043.092243396187, 5086.109930762121, 5086.415062267831, 5091.796328146888, 5014.799408023675, 5023.422306434771, 5043.974780126095, 5075.695458272516, 5088.135017047092, 5145.073886700392, 5152.742362881841, 5161.896762064775, 5167.7932882684645, 5190.844274671747, 4921.876953076166, 4922.0, 4966.726830024347, 5053.065182941167, 5059.544640982293, 5378.811741589026, 5470.17947307903, 5476.17857321401, 5490.011797876381, 5530.806421775219, 5979.730912108922, 5981.611195272337, 6008.579399442041, 6036.426539478743, 6044.486218671418, 5944.405559444064, 6007.459328660441, 6010.368911264298, 6020.006698894721, 6087.29996050451, 5633.633813802131, 5677.261955945701, 5682.602217844753, 5698.31620205578, 5699.57253206007, 6001.399860014051, 6205.720742566596, 6218.0, 6219.968900155471, 6222.0, 5814.186013958049, 5838.474537291664, 5854.209681692917, 5949.405059494103, 5950.613210141327, 5988.9101663475085, 5989.221401217901, 5990.5507086968655, 6015.30823955242, 6024.337322894478, 5696.060150075191, 5841.269841269841, 5957.76168953237, 5969.134475501028, 5970.283565972111, 5882.529397648178, 5940.376260492586, 5943.58394912352, 5949.196858424144, 5980.162777211194, 4624.0, 4631.166390049823, 4632.583067523871, 4634.86095417138, 4659.510751371125, 4032.0121570215483, 4082.101937573729, 4101.0, 4113.0, 4141.4409054777825, 5753.8561535961935, 5754.712264386764, 5757.827265182052, 5779.0, 5800.738966746507, 5914.497267732205, 5970.134330522137, 5978.521718262528, 5982.341942386334, 6000.219971403777, 5584.553235741131, 5659.943400566024, 5673.375928647845, 5685.147227915846, 5753.165790960287, 5202.739863006835, 5205.55752761012, 5206.0368831766245, 5206.583473322125, 5211.530962213419, 6246.406591373812, 6276.497880169575, 6279.120923070774, 6283.214598175228, 6286.0, 5825.417458254141, 5913.940860591511, 5956.731947062306, 5974.40255974399, 6016.8796624068145, 6299.401556852092, 6312.242530896322, 6320.5575609706875, 6323.715432805443, 6334.6832658365975, 5656.462636049569, 5889.911651325147, 5892.7642894283745, 5896.469317761251, 5929.0513517836935, 5842.036064049555, 5870.823875283749, 5885.14665373527, 5911.733971971185, 5918.556108291981, 5740.770369185186, 5782.797602083907, 5782.855428614318, 5786.826395208151, 5788.623739456922, 5670.347909990404, 5721.656700597978, 5758.884822303614, 5769.990251706018, 5777.480026797609, 5683.346415162309, 5715.799947001835, 5719.971400142973, 5743.798967036134, 5767.221425107557, 5853.736581853742, 5859.2382990211, 5909.202257695156, 5909.6158749681135, 5915.70421478933, 5533.391326953952, 5653.038983372754, 5699.772009119589, 5761.971190144022, 5781.595288329777], "value": 5513.88177408425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.2463457629826, 643.9742410303587, 658.3844105761113, 660.7621256347717, 668.6556423441926, 33804.0, 46925.29612055822, 48310.27534586984, 48364.274535881945, 48388.51611483893, 49900.50099499013, 50456.747716261365, 50500.0, 50560.49439505604, 50598.24102638463, 8597.140285971416, 9084.682036128737, 9305.209057230144, 10818.891811081907, 11125.388103654295, 10926.508307126198, 11036.503357348898, 11045.28205666633, 11045.558177672883, 11048.0, 10801.945990270038, 10858.077063449617, 10861.728456788565, 10866.402347870864, 10879.510422031028, 8600.311975041981, 11119.833202501968, 11389.715257118556, 11409.429528523624, 11409.714757131138, 10751.0, 10834.349939003685, 10846.078083362923, 11194.608188713437, 11208.439578021067, 45661.771691141665, 46260.306095408596, 46317.30524042142, 46478.0, 46483.30275045877, 3664.908377290589, 3700.2414505026404, 3724.0689827543115, 3728.738988270822, 4159.272127377697, 35053.12367190815, 35496.46755298672, 35541.82229088864, 35765.0, 35951.82024089889, 4213.389058586487, 4227.302495088306, 4321.416608757809, 4348.152110338485, 4387.100644367897, 377371.0, 380020.0, 380251.0987445073, 380373.0, 382165.0, 39998.80000599979, 41063.58936410657, 63811.0, 64251.35748642547, 64257.0, 42448.57551424508, 42751.78624106922, 42921.0, 42962.355564666555, 42963.14073718508, 15261.389544418322, 16841.663166736842, 16990.0, 17008.914955425145, 17018.744718829228, 13549.119307244997, 13745.931270343584, 13765.38055787492, 14126.64683382903, 14252.073615215186, 9398.624055037722, 9518.619255229853, 9519.048095190425, 9567.952160239294, 9572.377795443273, 3624.3294990426857, 3627.2926779277786, 3640.162762564625, 3699.8335074921156, 3711.536057992751, 1052.600011995437, 1095.895889890459, 1106.397013627575, 1178.929264244131, 1224.400043978444, 34960.825195873855, 35170.12074698102, 35218.295634087684, 35341.82329088338, 35393.29213415717, 1122.966311010671, 1159.8260260960924, 1219.2257916223261, 1222.144498850808, 1237.5915947737226, 18232.36186733458, 18267.72598411025, 18350.724739128924, 18635.906820466083, 18640.72038919417, 161595.0, 163306.0, 163885.0, 163926.0, 164124.0, 66328.0, 67828.6608566954, 67837.0, 67838.3216167832, 67863.0, 1331.5605850069453, 1445.8915581331441, 1458.4239225505867, 1479.2012313350886, 1504.5335945856818, 34286.31427371489, 35574.82212589024, 35792.64207357893, 36088.4586731194, 36186.0, 56667.0, 56676.716616416656, 57229.0, 57386.13920791275, 57426.7128664354, 445833.0, 447652.0, 448029.0, 448212.0, 448378.0, 28697.856510717313, 32623.510647340783, 33648.32703345871, 33655.15862103467, 33687.83156084302, 52898.73550632222, 53775.19337209867, 54189.0, 54202.45797542132, 54817.1777423331, 45193.774031130946, 46366.30450543312, 46660.76669616766, 47014.0, 47082.76458617685, 45152.0, 46029.53970460386, 46054.0, 46702.29946550736, 47940.520594795, 45658.54341456543, 46559.53440465552, 46622.53377466182, 46762.766186168854, 46975.0, 39257.411138833515, 40214.994625134605, 40385.59614403936, 41442.1711565761, 41649.16701665889, 29970.850145749133, 30037.249068773457, 30114.698853012065, 30438.69561304447, 30694.84652576723], "value": 20251.279703519882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.93400989851517, 439.973601583905, 440.60345688880017, 444.85097492340066, 449.5482040549247, 18680.87914725116, 19456.9027154864, 20450.590988180247, 20544.38366848997, 20548.07533660985, 18852.0, 19056.428307150774, 19657.115429805657, 19725.50686232845, 19745.70381444276, 5712.22884910538, 5866.266716660418, 5881.529477641779, 5893.675847828367, 5906.11408288758, 5690.146478028309, 5709.6574205547595, 5749.741261643215, 5808.070708686611, 5896.498797602209, 5844.561657875676, 5845.766169353221, 5851.79518716845, 5877.764889404419, 5881.382454842244, 5599.188117722928, 5809.854753631151, 5821.767129314801, 5825.330087040003, 5830.242068531085, 5233.555147812441, 5238.978399212155, 5240.34495688039, 5249.921251181236, 5251.317328747276, 21680.0, 23389.298321050395, 23689.7631023691, 23709.88145059281, 24560.0, 2464.531738969608, 2765.3777899972347, 2781.123945957017, 2796.2170592234206, 3079.538069289602, 14477.493287734984, 14983.0, 15010.324535395934, 15230.0, 15417.460388886335, 2025.9696104558443, 2030.4517780199265, 2050.6001329740707, 2145.5279838435517, 2151.7633060363346, 79468.0, 80909.78635320475, 82285.58857205676, 82422.58788706078, 84019.57990210071, 10943.398113103696, 11359.0, 11541.942290288494, 11589.24669896454, 11701.718164957752, 16270.26783794732, 16306.347746090261, 16319.592010199602, 16335.673286534202, 16364.099974501278, 8845.69040083594, 9030.593623286968, 9125.269978401844, 9168.40405373662, 9182.403143795633, 8721.694740684046, 8744.69393571222, 8844.071372505923, 8880.467171969573, 8968.506732129796, 6951.478639102088, 7007.824804379931, 7117.964410177916, 7209.2069872314005, 7213.747518836815, 1794.9155434226445, 1955.4817973237105, 2033.6136134134467, 2081.6669332906663, 2084.9944187529886, 905.6694306578052, 1055.8205105132147, 1060.8408738689104, 1067.4555976451911, 1109.8612673415823, 20045.298414555422, 20155.596888062155, 20159.798402016084, 20495.0, 20496.487587810425, 1065.2862582069877, 1073.018188357644, 1080.189857606795, 1098.780243951213, 1109.7946879827257, 11410.37242951629, 11668.241564298294, 12032.879671203176, 12041.157119001586, 12058.336791476377, 366626.0, 368904.0, 474215.0, 486327.0, 488169.0, 62091.689541552005, 71481.0, 103604.48197758963, 105220.47389763156, 106144.0, 1032.4785983078498, 1050.4800123938546, 1091.1216470740972, 1093.495894401811, 1098.9890101098886, 18193.727094093334, 18207.726884096483, 18314.0, 18562.535936601693, 18643.90678046601, 46002.53997460116, 46588.53411465842, 46713.0, 47071.0, 48116.518834811206, 64390.678046609464, 64621.67689161524, 66052.0, 66088.0, 66605.0, 8892.466452012899, 9125.45247285165, 9128.49793261377, 9241.630334786802, 9613.711588652352, 13448.529301474402, 13588.388522516709, 13757.38091785852, 13817.0, 14198.0, 11887.583934562595, 12141.392930353624, 12597.87402125967, 12643.620691379274, 12680.74638507243, 11830.231034982698, 11867.406629668654, 12085.335306558223, 12091.274523528617, 12137.878621214028, 12093.274403535816, 12208.32854193028, 12459.626211213677, 12581.559645412366, 12632.305223212812, 9242.630294788134, 9411.670591529264, 9414.952925235604, 9415.917169525592, 9544.624054722854, 9535.809283814424, 11563.42182890869, 12620.936895315464, 12801.487940482277, 13124.737505250032], "value": 9864.294771685622, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.377639503211, 1573.614464456208, 1609.0989046134164, 1835.85313174946, 1951.3365455745043, 9279.164875161234, 9831.75420614485, 9844.212463002952, 10117.241206909475, 10127.797444051133, 9923.404595724262, 10131.442770647629, 10145.391276523394, 10153.441560714158, 10153.441560714158, 2200.8569442986154, 2273.863568185914, 2496.1263557754824, 2576.446064096224, 2605.71337152913, 2588.2752829207852, 2618.1621880998086, 2620.161548304534, 2624.540705376561, 2625.855577943212, 2517.8363406378617, 2581.935451613706, 2583.3154214133174, 2583.780378667806, 2584.095566551711, 2127.617028934791, 2441.2676197140786, 2499.3126890105277, 2504.3613878460874, 2522.1928982725535, 2498.263012411343, 2498.9875050624814, 2517.8363406378526, 2524.066095544642, 2545.898164073435, 10045.347052441568, 10116.848247276297, 10121.342112762646, 10123.29136960413, 10126.240531960133, 5904.911426328608, 6067.18093057436, 6068.757249710006, 6071.423214794588, 6079.483243924272, 3734.178480734234, 3870.2259548090497, 3883.514560679915, 3889.766614003169, 3921.901952451183, 1221.8900298973047, 1296.6239790460754, 1314.625331780438, 1345.9125156864773, 1354.28223041788, 14357.569272921724, 14393.496227632088, 14398.71202575953, 14403.567892963229, 14420.711585768226, 5086.54221120101, 5165.405978312542, 5241.31862857826, 5242.475752424727, 5249.291345668362, 11766.294022358687, 11815.05479561633, 11816.527338906348, 11825.940870295593, 11827.231229970024, 5929.940700592939, 5953.791617293374, 5957.4042595740075, 5958.076498142795, 5970.641761494324, 4593.28804035375, 5355.0, 5521.668699878021, 5525.254090697785, 5613.663180209119, 4471.7987690554, 4504.459464864237, 4504.977475112603, 4542.704724192917, 4544.431946006749, 1373.7527245095978, 1383.3083458270864, 1400.1179257068186, 1407.7817938219591, 1411.7600007998665, 1004.8191325561323, 1053.89461053894, 1057.434272664121, 1071.5499490214129, 1090.9890901090887, 6442.35576442352, 6669.966650166718, 6754.932450675528, 6774.559653622598, 6839.863202735917, 489.8456986049383, 490.43109992408637, 495.5391485918059, 498.7680728461283, 506.0839879817542, 4413.492448368414, 4474.836542498973, 4475.888102797456, 4481.619062379734, 4508.278675411918, 12390.256584604953, 12760.042996775093, 12882.484700612058, 12892.0, 12903.935480322538, 12575.87424125747, 12648.936755316165, 12650.987920966485, 12662.0, 12663.556775512812, 8753.912460875437, 9102.772430689154, 9115.0, 9115.316351273681, 9116.908830911738, 6277.71750271248, 6453.515986300952, 6539.378759017885, 6550.672466376757, 6557.540972132096, 11074.557017719524, 11138.164637652046, 11192.328460292409, 11253.268537545033, 11356.0, 11343.376114313462, 11562.76874462523, 11648.184627076023, 11662.241954272946, 11675.708107297385, 7565.319121279112, 8576.099509551408, 8809.515476648847, 8812.91187088121, 8978.820423591622, 9876.111149996535, 9882.950585247028, 9966.302358834811, 9980.051895069957, 9985.350952188375, 8668.696595619123, 8845.513496757483, 8909.0, 8972.596233169654, 9042.276617870422, 8288.461250018729, 8300.916990830256, 8335.666573337, 8457.957710211409, 8476.152384761475, 7907.8813817793925, 7955.641996110305, 7996.960015199887, 8021.037475502977, 8022.518648881086, 5582.134769110794, 5688.260526131577, 5733.71331433435, 5851.795187168429, 5880.26496687914, 5494.587905906993, 5588.524975377139, 5691.8292451226525, 5715.257016587817, 5752.194692742935], "value": 5094.666384808427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.6934106009891, 363.351417719371, 364.96715295623386, 369.8834867016889, 373.1175769305593, 5364.356277246726, 5404.135338345865, 5486.286782718246, 5531.972340138299, 5558.444155584434, 5378.946210537893, 5406.40529541751, 5413.485718856702, 5459.590530710203, 5468.9179662305, 4115.773632450214, 4158.916821663565, 4284.4216030835905, 4311.331743579734, 4327.199468098412, 4270.444842170514, 4281.72168809028, 4307.504636966758, 4309.870703878889, 4324.329728892011, 4259.424977628011, 4264.893377665552, 4276.315789473685, 4283.100548884738, 4284.400183974246, 4026.4966879140106, 4260.829566817323, 4263.339182426729, 4268.103698223377, 4269.978650106761, 3770.1894092769912, 3772.5095737554216, 3774.8109345556063, 3781.357169281201, 3789.5073640426845, 5278.1818818082875, 5281.683099014072, 5296.0, 5316.6544174628525, 5326.334208223972, 4761.904761904777, 4919.1391506486225, 4955.925661115086, 4957.454679985199, 4963.081829861487, 5612.831615051514, 5636.843367714093, 5679.488846003879, 5715.428457154293, 5789.392113828029, 4139.544650088488, 4154.231467178552, 4161.854335098287, 4169.791510424467, 4179.958200417987, 5779.9422005779825, 5888.439563515672, 5923.496502797268, 5966.164736936789, 5966.463018328372, 4217.768022758778, 4221.936670949938, 4229.323308270662, 4250.149970006011, 4258.423653689535, 4283.657307415399, 4289.099289149313, 4314.784260786948, 4353.25994580922, 4353.847615333448, 4640.793393717658, 4754.619630429557, 4826.638002149853, 4873.244647079709, 4953.2074868020945, 5565.693886836263, 5667.943320566742, 5690.5447564194765, 5691.288588926384, 5695.231143795535, 1459.4599998000808, 1463.1952426165567, 1466.5746933389412, 1472.7569950957973, 1474.71243107593, 922.4787994782927, 942.2462030375672, 959.203860795538, 959.5921733263335, 959.8800149981253, 4994.500549945049, 5009.371954114928, 5017.8243761468175, 5033.84898453047, 5061.645684802103, 5158.845234642967, 5159.974200129051, 5166.13279629809, 5180.274761533388, 5183.792648294026, 5011.774470148852, 5033.949660503421, 5060.569851562658, 5203.661761985459, 5637.605367624227, 3083.953740693891, 3098.62816462026, 3121.531770234483, 3125.92155706281, 3126.3434678717267, 6119.388061193845, 6127.863692007877, 6132.50939924805, 6175.722092505848, 6178.876422471525, 5758.712064396764, 6049.5462840287155, 6056.788012419544, 6067.302260240041, 6080.847978800477, 6170.413810687977, 6171.783987560414, 6192.164057852222, 6215.875682486235, 6236.532260080512, 5649.0907182800065, 5877.0, 5884.382139875252, 5932.911006334822, 5942.910856337072, 5811.680357580204, 5828.358880523054, 5849.532037436909, 5852.239208902815, 5896.292444906639, 5785.82642520725, 6186.257649082139, 6204.3795620437595, 6224.0, 6241.407066328691, 4266.146770645945, 4307.483102027777, 4342.1532801103485, 4353.542877997765, 4367.519572846921, 5650.29371328584, 5780.682062486603, 5784.334801497882, 5797.449242321973, 5840.182374467493, 5724.914126288026, 5752.68360240174, 5762.106873434456, 5779.8555036124435, 5794.739236734273, 5640.605157638925, 5687.345955215038, 5708.143778433268, 5721.885562288814, 5735.738137609719, 5571.832845014656, 5576.972115139399, 5628.352739435018, 5648.237487939075, 5692.886142277214, 5737.741801618854, 5757.453041961007, 5762.337331206797, 5769.163471296554, 5781.884362312814, 5435.23906653061, 5621.437856214346, 5629.986602411605, 5657.0, 5672.290963629546], "value": 4356.706466331455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.125471943107, 790.6521130702487, 792.9572612015199, 802.8033131882688, 844.9704260350888, 15700.450484233053, 16998.150092495358, 17040.829591704078, 17104.05927673979, 17108.657826843457, 13079.411426485807, 15614.141222232773, 15763.84236157638, 15796.0, 15826.28781704823, 853.9402241843073, 880.2561835249236, 888.080836334394, 890.3011136258039, 890.4657205676607, 880.3705350674267, 885.0883589902379, 887.2369762004671, 888.3293113699181, 888.4402826219473, 753.9849203015937, 910.6493999810054, 913.6117150211167, 924.2375040591505, 926.5182105305271, 899.5322432335182, 906.2251774732574, 906.3746015249457, 907.6823111910844, 907.8728977943093, 671.392389887153, 672.4553111979312, 673.061079793685, 673.1383828699272, 673.1719984419143, 15428.074315541104, 15445.0, 15481.845181548153, 15489.380424782996, 15493.845061549353, 15327.080375177413, 15560.533184004498, 15613.921930390388, 15637.530874073796, 15815.762763558556, 13399.866001339959, 13625.523106691218, 13725.176489410567, 13751.449942002308, 13814.65463363414, 4708.529147085298, 4743.051389722069, 4781.330613714083, 4782.354382158399, 4890.193118135503, 80274.0, 80363.59818200872, 82503.0, 84679.15320846775, 84762.57618711867, 12243.081768867192, 12251.387430628432, 12294.938525307316, 12369.752604947851, 12643.178193417398, 23865.7613423867, 23932.7606723934, 23936.7606323938, 23941.7605823943, 23953.640695389582, 8685.91314086851, 8714.215720585178, 8764.605592748341, 8845.292376609856, 8985.236254918405, 4503.662225333113, 4612.262038073959, 4670.719756814602, 4892.608591312686, 4900.509949005071, 3596.9280614387567, 3697.2975134724315, 3708.8331025103394, 3711.0722319420147, 3713.0, 587.3040447070235, 661.0711949710665, 690.5890994858051, 690.5960013392122, 693.5041445366587, 606.3088079589272, 664.3788058165534, 726.7565365602583, 733.3729661139755, 794.7615715285391, 10669.573217071385, 10725.677237575072, 10772.407517586607, 10954.233203675667, 10961.890381096246, 636.4303947966637, 697.3793323941696, 718.9856202876018, 732.0848938826467, 747.8466914282559, 5063.645544811901, 5096.286519887219, 5166.870828229325, 5173.71544565045, 5325.227841962894, 75467.24532754603, 107235.4638226804, 124961.37519312528, 128814.0, 129956.0, 89594.0, 91350.0, 99552.50223748836, 100998.0, 104985.47507262568, 87793.0, 90558.0, 93559.0, 96308.0, 97835.51082244686, 10501.842472363074, 10543.527570202785, 10543.78912421732, 10655.094316983143, 10869.565217391531, 48520.02959940852, 52115.21827172671, 54964.45035549593, 55844.72077639586, 55916.0, 67998.32001680118, 68220.0, 69377.0, 69582.0, 69702.30297696959, 12782.296973666538, 13465.192088474723, 13673.384697688429, 13746.312684365541, 13772.31138443094, 15409.075455472708, 15554.611134721723, 15611.141387223812, 15623.765643515584, 15883.444079457164, 9967.750806229902, 13642.113262637897, 14036.228007459687, 14199.148051116967, 14920.403183872524, 14063.578092657237, 14162.079464834753, 14324.0, 14392.496262630757, 14572.708545828811, 13748.793768093687, 13939.233342165873, 14356.928215358856, 14420.351084201026, 14422.711545768816, 10635.627753028606, 10811.189160812813, 11077.944610276896, 12049.578264760692, 12186.0, 11732.882671173178, 11868.643940681794, 11941.82087268709, 12078.335691536699, 12195.51217951272], "value": 7976.898645921278, "unit": "score"}], "name": "dromaeo_css", "value": 7615.309969259542, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 102.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0, 109.0, 109.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 134.0, 126.0, 174.0, 126.0, 125.0, 148.0, 127.0, 166.0, 134.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 163.0, 162.0, 162.0, 160.0, 160.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 127.0, 87.0, 77.0, 75.0, 76.0, 99.0, 77.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 121.0, 85.0, 84.0, 84.0, 114.0, 84.0, 85.0, 122.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 52.0, 50.0, 53.0, 50.0, 52.0, 52.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 59.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 76.0, 79.0, 77.0, 78.0, 77.0, 106.0, 77.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 134.0, 117.0, 116.0, 120.0, 132.0, 120.0, 118.0, 126.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 155.0, 154.0, 163.0, 161.0, 157.0, 156.0, 154.0, 152.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 63.0, 64.0, 61.0, 63.0, 61.0, 60.0, 61.0, 60.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1480.7, "alertThreshold": 2.0}]} 03:51:25 INFO - Return code: 0 03:51:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:25 ERROR - # TBPL SUCCESS # 03:51:25 INFO - Running post-action listener: _resource_record_post_action 03:51:25 INFO - ##### 03:51:25 INFO - ##### Finished run-tests step (success) 03:51:25 INFO - ##### 03:51:25 INFO - Running post-run listener: _resource_record_post_run 03:51:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 508805120; Read time: 200; Write time: 392632 03:51:25 INFO - TinderboxPrint: CPU usage
13.1% 03:51:25 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 200 03:51:25 INFO - TinderboxPrint: I/O write bytes / time
508,805,120 / 392,632 03:51:25 INFO - TinderboxPrint: CPU idle
8,249.7 (86.9%) 03:51:25 INFO - TinderboxPrint: CPU user
1,197.8 (12.6%) 03:51:25 INFO - TinderboxPrint: Swap in / out
0 / 0 03:51:25 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252407808; Read time: 0; Write time: 230388 03:51:25 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 270336; Write bytes: 256229376; Read time: 200; Write time: 162224 03:51:26 INFO - Running post-run listener: _upload_blobber_files 03:51:26 INFO - Blob upload gear active. 03:51:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:26 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:26 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:26 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:26 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:51:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:29 INFO - (blobuploader) - INFO - Done attempting. 03:51:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:51:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:51:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:30 INFO - (blobuploader) - INFO - Done attempting. 03:51:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:51:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:51:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:31 INFO - (blobuploader) - INFO - Done attempting. 03:51:31 INFO - (blobuploader) - INFO - Iteration through files over. 03:51:31 INFO - Return code: 0 03:51:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:51:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:51:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e481423af764a86951887584c6a1ef900946a6f3c0bc7773e5993fe0f8babdb8cf503bc32535fbadbc77c0d8bc52b9a6f72d5f4e0fe33fb35aeb5d1ef1d757f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94491eb27e0550c02a43091405dba97e255ebb0e3ae40d9da64d83eaaa58042f4f4db245d86223d1cda33b63c5b3601deec500c262d3ab4da8e9ae2fd321e5f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0119c0eba76ad8f7a395f6d36dc7eed0c23f31050cf101b956f16ec0a36135ea8b08bb055cd18c6d786ad3d38dac84b6303e7f45146f85043a531e02c014fd3d"} 03:51:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:51:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:51:31 INFO - Contents: 03:51:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e481423af764a86951887584c6a1ef900946a6f3c0bc7773e5993fe0f8babdb8cf503bc32535fbadbc77c0d8bc52b9a6f72d5f4e0fe33fb35aeb5d1ef1d757f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94491eb27e0550c02a43091405dba97e255ebb0e3ae40d9da64d83eaaa58042f4f4db245d86223d1cda33b63c5b3601deec500c262d3ab4da8e9ae2fd321e5f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0119c0eba76ad8f7a395f6d36dc7eed0c23f31050cf101b956f16ec0a36135ea8b08bb055cd18c6d786ad3d38dac84b6303e7f45146f85043a531e02c014fd3d"} 03:51:31 INFO - Running post-run listener: copy_logs_to_upload_dir 03:51:31 INFO - Copying logs to upload dir... 03:51:31 INFO - mkdir: /builds/slave/test/build/upload/logs 03:51:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.914862 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-27 03:51:30.267074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:30.270562) ========= 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=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e481423af764a86951887584c6a1ef900946a6f3c0bc7773e5993fe0f8babdb8cf503bc32535fbadbc77c0d8bc52b9a6f72d5f4e0fe33fb35aeb5d1ef1d757f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94491eb27e0550c02a43091405dba97e255ebb0e3ae40d9da64d83eaaa58042f4f4db245d86223d1cda33b63c5b3601deec500c262d3ab4da8e9ae2fd321e5f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0119c0eba76ad8f7a395f6d36dc7eed0c23f31050cf101b956f16ec0a36135ea8b08bb055cd18c6d786ad3d38dac84b6303e7f45146f85043a531e02c014fd3d"} 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.011386 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e481423af764a86951887584c6a1ef900946a6f3c0bc7773e5993fe0f8babdb8cf503bc32535fbadbc77c0d8bc52b9a6f72d5f4e0fe33fb35aeb5d1ef1d757f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94491eb27e0550c02a43091405dba97e255ebb0e3ae40d9da64d83eaaa58042f4f4db245d86223d1cda33b63c5b3601deec500c262d3ab4da8e9ae2fd321e5f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0119c0eba76ad8f7a395f6d36dc7eed0c23f31050cf101b956f16ec0a36135ea8b08bb055cd18c6d786ad3d38dac84b6303e7f45146f85043a531e02c014fd3d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:30.296895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:30.297295) ========= 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=20e666a3d4cfbb71505a330e000001b4-1467023455.948228-1398448459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004695 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 03:51:30.381878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 03:51:30.382263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 03:51:30.382716) ========= ========= Total master_lag: 0.18 =========