builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1466698278.54 results: success (0) buildid: 20160623063124 builduid: 81edcb102f834b7d8f41633f1779600d revision: d4e7738bd2aa5a523ca2551c467458c9f3eac45a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.544314) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.544772) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.545112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.579508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.579809) ========= 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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-23 09:11:18-- 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% 216M=0s 2016-06-23 09:11:18 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147631 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.744812) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.745385) ========= 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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014718 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:18.797015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 09:11:18.797601) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d4e7738bd2aa5a523ca2551c467458c9f3eac45a --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 d4e7738bd2aa5a523ca2551c467458c9f3eac45a --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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-23 09:11:18,852 truncating revision to first 12 chars 2016-06-23 09:11:18,852 Setting DEBUG logging. 2016-06-23 09:11:18,852 attempt 1/10 2016-06-23 09:11:18,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d4e7738bd2aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 09:11:20,180 unpacking tar archive at: mozilla-aurora-d4e7738bd2aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.666814 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 09:11:20.482130) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:20.482816) ========= script_repo_revision: d4e7738bd2aa5a523ca2551c467458c9f3eac45a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:20.483702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:20.484361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 09:11:20.500317) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-23 09:11:20.500627) ========= /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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:20 INFO - MultiFileLogger online at 20160623 09:11:20 in /builds/slave/test 09:11:20 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 09:11:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:20 INFO - {'append_to_log': False, 09:11:20 INFO - 'base_work_dir': '/builds/slave/test', 09:11:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:11:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:20 INFO - 'branch': 'Mozilla-Aurora', 09:11:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:20 INFO - 'config_files': ('talos/linux_config.py',), 09:11:20 INFO - 'default_actions': ('clobber', 09:11:20 INFO - 'read-buildbot-config', 09:11:20 INFO - 'download-and-extract', 09:11:20 INFO - 'populate-webroot', 09:11:20 INFO - 'create-virtualenv', 09:11:20 INFO - 'install', 09:11:20 INFO - 'run-tests'), 09:11:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:20 INFO - 'download_minidump_stackwalk': True, 09:11:20 INFO - 'download_symbols': 'ondemand', 09:11:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:20 INFO - 'installer_path': 'installer.exe', 09:11:20 INFO - 'log_level': 'info', 09:11:20 INFO - 'log_name': 'talos', 09:11:20 INFO - 'log_to_console': True, 09:11:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:20 INFO - 'opt_config_files': (), 09:11:20 INFO - 'pip_index': False, 09:11:20 INFO - 'sps_profile': False, 09:11:20 INFO - 'sps_profile_interval': 0, 09:11:20 INFO - 'suite': 'dromaeojs', 09:11:20 INFO - 'system_bits': '32', 09:11:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:20 INFO - 'title': 'talos-linux64-ix-047', 09:11:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:20 INFO - 'use_talos_json': True, 09:11:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:20 INFO - 'work_dir': 'build'} 09:11:20 INFO - ##### 09:11:20 INFO - ##### Running clobber step. 09:11:20 INFO - ##### 09:11:20 INFO - Running pre-action listener: _resource_record_pre_action 09:11:20 INFO - Running main action method: clobber 09:11:20 INFO - rmtree: /builds/slave/test/build 09:11:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:21 INFO - Running post-action listener: _resource_record_post_action 09:11:21 INFO - ##### 09:11:21 INFO - ##### Finished clobber step (success) 09:11:21 INFO - ##### 09:11:21 INFO - ##### 09:11:21 INFO - ##### Running read-buildbot-config step. 09:11:21 INFO - ##### 09:11:21 INFO - Running pre-action listener: _resource_record_pre_action 09:11:21 INFO - Running main action method: read_buildbot_config 09:11:21 INFO - Using buildbot properties: 09:11:21 INFO - { 09:11:21 INFO - "project": "", 09:11:21 INFO - "product": "firefox", 09:11:21 INFO - "script_repo_revision": "production", 09:11:21 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:11:21 INFO - "repository": "", 09:11:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:11:21 INFO - "buildid": "20160623063124", 09:11:21 INFO - "pgo_build": "True", 09:11:21 INFO - "basedir": "/builds/slave/test", 09:11:21 INFO - "buildnumber": 572, 09:11:21 INFO - "slavename": "talos-linux64-ix-047", 09:11:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:11:21 INFO - "platform": "ubuntu64_hw", 09:11:21 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:11:21 INFO - "branch": "mozilla-aurora", 09:11:21 INFO - "repo_path": "releases/mozilla-aurora", 09:11:21 INFO - "revision": "d4e7738bd2aa5a523ca2551c467458c9f3eac45a", 09:11:21 INFO - "stage_platform": "linux64-pgo", 09:11:21 INFO - "builduid": "81edcb102f834b7d8f41633f1779600d", 09:11:21 INFO - "slavebuilddir": "test" 09:11:21 INFO - } 09:11:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:11:21 INFO - Running post-action listener: _resource_record_post_action 09:11:21 INFO - ##### 09:11:21 INFO - ##### Finished read-buildbot-config step (success) 09:11:21 INFO - ##### 09:11:21 INFO - ##### 09:11:21 INFO - ##### Running download-and-extract step. 09:11:21 INFO - ##### 09:11:21 INFO - Running pre-action listener: _resource_record_pre_action 09:11:21 INFO - Running main action method: download_and_extract 09:11:21 INFO - mkdir: /builds/slave/test/build/tests 09:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:21 INFO - https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:11:27 INFO - Downloaded 1361 bytes. 09:11:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:11:27 INFO - Using the following test package requirements: 09:11:27 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:11:27 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:27 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:11:27 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'jsshell-linux-x86_64.zip'], 09:11:27 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:27 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:11:27 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:11:27 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:11:27 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:27 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:11:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:27 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:27 INFO - https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:11:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:11:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:11:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:07 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip: The read operation timed out 09:12:07 INFO - retry: attempt #1 caught exception: The read operation timed out 09:12:07 INFO - retry: Failed, sleeping 30 seconds before retrying 09:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 #2 09:12:52 INFO - Downloaded 24474579 bytes. 09:12:52 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'] 09:12:52 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 09:12:53 INFO - Return code: 0 09:12:53 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 09:12:53 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')]} 09:12:53 INFO - https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:12:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:12:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:12:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:54 INFO - Downloaded 24474579 bytes. 09:12:54 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'] 09:12:54 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 09:12:56 INFO - Return code: 0 09:12:56 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')]} 09:12:56 INFO - https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:12:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:12:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:12:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:57 INFO - Downloaded 10833369 bytes. 09:12:57 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'] 09:12:57 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 09:12:57 INFO - Return code: 0 09:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:57 INFO - https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:12:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:12:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:12:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aartmEjXR3KKW6ShCTgBKg/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 09:12:58 INFO - Downloaded 63612534 bytes. 09:12:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:12:58 INFO - mkdir: /builds/slave/test/properties 09:12:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:58 INFO - Contents: 09:12:58 INFO - build_url:https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:12:59 INFO - Running post-action listener: _resource_record_post_action 09:12:59 INFO - Running post-action listener: set_extra_try_arguments 09:12:59 INFO - ##### 09:12:59 INFO - ##### Finished download-and-extract step (success) 09:12:59 INFO - ##### 09:12:59 INFO - ##### 09:12:59 INFO - ##### Running populate-webroot step. 09:12:59 INFO - ##### 09:12:59 INFO - Running pre-action listener: _resource_record_pre_action 09:12:59 INFO - Running main action method: populate_webroot 09:12:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:59 INFO - '--tppagecycles', 09:12:59 INFO - '7'], 09:12:59 INFO - 'tests': ['tsvgm']}}, 09:12:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['damp', 'tps']}, 09:12:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'tests': ['damp', 'tps']}, 09:12:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['dromaeo_dom']}, 09:12:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['a11yr', 09:12:59 INFO - 'ts_paint', 09:12:59 INFO - 'tpaint', 09:12:59 INFO - 'sessionrestore', 09:12:59 INFO - 'sessionrestore_no_auto_restore', 09:12:59 INFO - 'tabpaint']}, 09:12:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['tsvgx', 09:12:59 INFO - 'tsvgr_opacity', 09:12:59 INFO - 'tart', 09:12:59 INFO - 'tscrollx', 09:12:59 INFO - 'cart']}, 09:12:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:59 INFO - 'tsvgr_opacity', 09:12:59 INFO - 'tart', 09:12:59 INFO - 'tscrollx', 09:12:59 INFO - 'cart']}, 09:12:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'talos_options': ['--disable-e10s'], 09:12:59 INFO - 'tests': ['tp5o']}, 09:12:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'tests': ['tp5o']}, 09:12:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'talos_options': ['--disable-e10s', 09:12:59 INFO - '--xperf_path', 09:12:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:59 INFO - 'tests': ['tp5n']}, 09:12:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:59 INFO - 'talos_options': ['--xperf_path', 09:12:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:59 INFO - 'tests': ['tp5n']}}, 09:12:59 INFO - 'talos.zip': {'path': '', 09:12:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:59 INFO - Running post-action listener: _resource_record_post_action 09:12:59 INFO - ##### 09:12:59 INFO - ##### Finished populate-webroot step (success) 09:12:59 INFO - ##### 09:12:59 INFO - ##### 09:12:59 INFO - ##### Running create-virtualenv step. 09:12:59 INFO - ##### 09:12:59 INFO - Running pre-action listener: _resource_record_pre_action 09:12:59 INFO - Running main action method: create_virtualenv 09:12:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:59 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 09:12:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:59 INFO - Using real prefix '/usr' 09:12:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:02 INFO - Installing pip.................done. 09:13:02 INFO - Return code: 0 09:13:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:02 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:02 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 09:13:02 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 09:13:02 INFO - Using env: {'DISPLAY': ':0', 09:13:02 INFO - 'HOME': '/home/cltbld', 09:13:02 INFO - 'LANG': 'en_US.UTF-8', 09:13:02 INFO - 'LANGUAGE': 'en_US:en', 09:13:02 INFO - 'LOGNAME': 'cltbld', 09:13:02 INFO - 'MAIL': '/var/mail/cltbld', 09:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:02 INFO - 'MOZ_NO_REMOTE': '1', 09:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:02 INFO - 'NO_EM_RESTART': '1', 09:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:02 INFO - 'PWD': '/builds/slave/test', 09:13:02 INFO - 'SHELL': '/bin/bash', 09:13:02 INFO - 'SHLVL': '1', 09:13:02 INFO - 'TERM': 'linux', 09:13:02 INFO - 'TMOUT': '86400', 09:13:02 INFO - 'USER': 'cltbld', 09:13:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:02 INFO - '_': '/tools/buildbot/bin/python'} 09:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:02 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 09:13:02 INFO - Cleaning up... 09:13:02 INFO - Return code: 0 09:13:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:02 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:02 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 09:13:02 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 09:13:02 INFO - Using env: {'DISPLAY': ':0', 09:13:02 INFO - 'HOME': '/home/cltbld', 09:13:02 INFO - 'LANG': 'en_US.UTF-8', 09:13:02 INFO - 'LANGUAGE': 'en_US:en', 09:13:02 INFO - 'LOGNAME': 'cltbld', 09:13:02 INFO - 'MAIL': '/var/mail/cltbld', 09:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:02 INFO - 'MOZ_NO_REMOTE': '1', 09:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:02 INFO - 'NO_EM_RESTART': '1', 09:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:02 INFO - 'PWD': '/builds/slave/test', 09:13:02 INFO - 'SHELL': '/bin/bash', 09:13:02 INFO - 'SHLVL': '1', 09:13:02 INFO - 'TERM': 'linux', 09:13:02 INFO - 'TMOUT': '86400', 09:13:02 INFO - 'USER': 'cltbld', 09:13:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:02 INFO - '_': '/tools/buildbot/bin/python'} 09:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:03 INFO - Downloading/unpacking psutil>=3.1.1 09:13:03 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 09:13:03 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 09:13:03 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 09:13:03 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 09:13:03 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 09:13:03 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 09:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:05 INFO - Installing collected packages: psutil 09:13:05 INFO - Running setup.py install for psutil 09:13:05 INFO - building 'psutil._psutil_linux' extension 09:13:05 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 09:13:06 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 09:13:06 INFO - building 'psutil._psutil_posix' extension 09:13:06 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 09:13:06 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 09:13:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:07 INFO - Successfully installed psutil 09:13:07 INFO - Cleaning up... 09:13:07 INFO - Return code: 0 09:13:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:07 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:07 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 09:13:07 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 09:13:07 INFO - Using env: {'DISPLAY': ':0', 09:13:07 INFO - 'HOME': '/home/cltbld', 09:13:07 INFO - 'LANG': 'en_US.UTF-8', 09:13:07 INFO - 'LANGUAGE': 'en_US:en', 09:13:07 INFO - 'LOGNAME': 'cltbld', 09:13:07 INFO - 'MAIL': '/var/mail/cltbld', 09:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:07 INFO - 'MOZ_NO_REMOTE': '1', 09:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:07 INFO - 'NO_EM_RESTART': '1', 09:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:07 INFO - 'PWD': '/builds/slave/test', 09:13:07 INFO - 'SHELL': '/bin/bash', 09:13:07 INFO - 'SHLVL': '1', 09:13:07 INFO - 'TERM': 'linux', 09:13:07 INFO - 'TMOUT': '86400', 09:13:07 INFO - 'USER': 'cltbld', 09:13:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:07 INFO - '_': '/tools/buildbot/bin/python'} 09:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:07 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 09:13:07 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 09:13:07 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 09:13:07 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 09:13:07 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 09:13:07 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 09:13:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:09 INFO - Installing collected packages: mozsystemmonitor 09:13:09 INFO - Running setup.py install for mozsystemmonitor 09:13:09 INFO - Successfully installed mozsystemmonitor 09:13:09 INFO - Cleaning up... 09:13:09 INFO - Return code: 0 09:13:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:09 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:09 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 09:13:09 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 09:13:09 INFO - Using env: {'DISPLAY': ':0', 09:13:09 INFO - 'HOME': '/home/cltbld', 09:13:09 INFO - 'LANG': 'en_US.UTF-8', 09:13:09 INFO - 'LANGUAGE': 'en_US:en', 09:13:09 INFO - 'LOGNAME': 'cltbld', 09:13:09 INFO - 'MAIL': '/var/mail/cltbld', 09:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:09 INFO - 'MOZ_NO_REMOTE': '1', 09:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:09 INFO - 'NO_EM_RESTART': '1', 09:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:09 INFO - 'PWD': '/builds/slave/test', 09:13:09 INFO - 'SHELL': '/bin/bash', 09:13:09 INFO - 'SHLVL': '1', 09:13:09 INFO - 'TERM': 'linux', 09:13:09 INFO - 'TMOUT': '86400', 09:13:09 INFO - 'USER': 'cltbld', 09:13:09 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:09 INFO - '_': '/tools/buildbot/bin/python'} 09:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:09 INFO - Downloading/unpacking jsonschema==2.5.1 09:13:09 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 09:13:09 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 09:13:09 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 09:13:09 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 09:13:09 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 09:13:09 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 09:13:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13:11 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 09:13:11 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 09:13:11 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 09:13:11 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 09:13:11 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 09:13:11 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 09:13:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:11 INFO - warning: no files found matching '*.txt' 09:13:11 INFO - no previously-included directories found matching 'build' 09:13:11 INFO - no previously-included directories found matching 'dist' 09:13:11 INFO - no previously-included directories found matching '.git*' 09:13:11 INFO - Installing collected packages: jsonschema, functools32 09:13:11 INFO - Running setup.py install for functools32 09:13:11 INFO - warning: no files found matching '*.txt' 09:13:11 INFO - no previously-included directories found matching 'build' 09:13:11 INFO - no previously-included directories found matching 'dist' 09:13:11 INFO - no previously-included directories found matching '.git*' 09:13:11 INFO - Successfully installed jsonschema functools32 09:13:11 INFO - Cleaning up... 09:13:12 INFO - Return code: 0 09:13:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13:12 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')]} 09:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:12 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')]} 09:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:12 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:12 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 09:13:12 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 09:13:12 INFO - Using env: {'DISPLAY': ':0', 09:13:12 INFO - 'HOME': '/home/cltbld', 09:13:12 INFO - 'LANG': 'en_US.UTF-8', 09:13:12 INFO - 'LANGUAGE': 'en_US:en', 09:13:12 INFO - 'LOGNAME': 'cltbld', 09:13:12 INFO - 'MAIL': '/var/mail/cltbld', 09:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:12 INFO - 'MOZ_NO_REMOTE': '1', 09:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:12 INFO - 'NO_EM_RESTART': '1', 09:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:12 INFO - 'PWD': '/builds/slave/test', 09:13:12 INFO - 'SHELL': '/bin/bash', 09:13:12 INFO - 'SHLVL': '1', 09:13:12 INFO - 'TERM': 'linux', 09:13:12 INFO - 'TMOUT': '86400', 09:13:12 INFO - 'USER': 'cltbld', 09:13:12 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:12 INFO - '_': '/tools/buildbot/bin/python'} 09:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:12 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:12 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 09:13:12 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 09:13:12 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 09:13:12 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 09:13:12 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 09:13:12 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 09:13:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:14 INFO - Downloading docopt-0.6.1.tar.gz 09:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:14 INFO - Running setup.py install for blobuploader 09:13:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:14 INFO - Running setup.py install for requests 09:13:15 INFO - Running setup.py install for docopt 09:13:15 INFO - Successfully installed blobuploader requests docopt 09:13:15 INFO - Cleaning up... 09:13:15 INFO - Return code: 0 09:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:15 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')]} 09:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:15 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')]} 09:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:15 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:15 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 09:13:15 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 09:13:15 INFO - Using env: {'DISPLAY': ':0', 09:13:15 INFO - 'HOME': '/home/cltbld', 09:13:15 INFO - 'LANG': 'en_US.UTF-8', 09:13:15 INFO - 'LANGUAGE': 'en_US:en', 09:13:15 INFO - 'LOGNAME': 'cltbld', 09:13:15 INFO - 'MAIL': '/var/mail/cltbld', 09:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:15 INFO - 'MOZ_NO_REMOTE': '1', 09:13:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:15 INFO - 'NO_EM_RESTART': '1', 09:13:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:15 INFO - 'PWD': '/builds/slave/test', 09:13:15 INFO - 'SHELL': '/bin/bash', 09:13:15 INFO - 'SHLVL': '1', 09:13:15 INFO - 'TERM': 'linux', 09:13:15 INFO - 'TMOUT': '86400', 09:13:15 INFO - 'USER': 'cltbld', 09:13:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:15 INFO - '_': '/tools/buildbot/bin/python'} 09:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:15 INFO - Running setup.py (path:/tmp/pip-7TqLF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:15 INFO - Running setup.py (path:/tmp/pip-Aqo0ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:15 INFO - Running setup.py (path:/tmp/pip-9d4fN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:15 INFO - Running setup.py (path:/tmp/pip-9e5EAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:15 INFO - Running setup.py (path:/tmp/pip-cRrCed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:16 INFO - Running setup.py (path:/tmp/pip-6Ocvgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:16 INFO - Running setup.py (path:/tmp/pip-NWPrKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:16 INFO - Running setup.py (path:/tmp/pip-uI5x1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:16 INFO - Running setup.py (path:/tmp/pip-_Yl4lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:16 INFO - Running setup.py (path:/tmp/pip-FlXN3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:16 INFO - Running setup.py (path:/tmp/pip-zBnsZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:16 INFO - Running setup.py (path:/tmp/pip-rhYIxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:16 INFO - Running setup.py (path:/tmp/pip-w5NkX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:16 INFO - Running setup.py (path:/tmp/pip-wfB7Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:16 INFO - Running setup.py (path:/tmp/pip-xN4voW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:16 INFO - Running setup.py (path:/tmp/pip-kTw6LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:16 INFO - Running setup.py (path:/tmp/pip-rS7kYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:16 INFO - Running setup.py install for manifestparser 09:13:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for mozcrash 09:13:17 INFO - Running setup.py install for mozdebug 09:13:17 INFO - Running setup.py install for mozdevice 09:13:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for mozfile 09:13:17 INFO - Running setup.py install for mozhttpd 09:13:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for mozinfo 09:13:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for mozInstall 09:13:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for mozleak 09:13:17 INFO - Running setup.py install for mozlog 09:13:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:17 INFO - Running setup.py install for moznetwork 09:13:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Running setup.py install for mozprocess 09:13:18 INFO - Running setup.py install for mozprofile 09:13:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Running setup.py install for mozrunner 09:13:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Running setup.py install for mozscreenshot 09:13:18 INFO - Running setup.py install for moztest 09:13:18 INFO - Running setup.py install for mozversion 09:13:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:18 INFO - Cleaning up... 09:13:18 INFO - Return code: 0 09:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:18 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:18 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 09:13:18 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 09:13:18 INFO - Using env: {'DISPLAY': ':0', 09:13:18 INFO - 'HOME': '/home/cltbld', 09:13:18 INFO - 'LANG': 'en_US.UTF-8', 09:13:18 INFO - 'LANGUAGE': 'en_US:en', 09:13:18 INFO - 'LOGNAME': 'cltbld', 09:13:18 INFO - 'MAIL': '/var/mail/cltbld', 09:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:18 INFO - 'MOZ_NO_REMOTE': '1', 09:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:18 INFO - 'NO_EM_RESTART': '1', 09:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:18 INFO - 'PWD': '/builds/slave/test', 09:13:18 INFO - 'SHELL': '/bin/bash', 09:13:18 INFO - 'SHLVL': '1', 09:13:18 INFO - 'TERM': 'linux', 09:13:18 INFO - 'TMOUT': '86400', 09:13:18 INFO - 'USER': 'cltbld', 09:13:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:18 INFO - '_': '/tools/buildbot/bin/python'} 09:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:18 INFO - Running setup.py (path:/tmp/pip-h7UysB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:18 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)) 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:19 INFO - Running setup.py (path:/tmp/pip-5xCufi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:19 INFO - Running setup.py (path:/tmp/pip-nUDuRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:19 INFO - Running setup.py (path:/tmp/pip-tPrENK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:19 INFO - Running setup.py (path:/tmp/pip-tBgNjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:19 INFO - Running setup.py (path:/tmp/pip-w52oLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:19 INFO - Running setup.py (path:/tmp/pip-UVoGbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:19 INFO - Running setup.py (path:/tmp/pip-k1XOw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:19 INFO - Running setup.py (path:/tmp/pip-ZAQcJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:19 INFO - Running setup.py (path:/tmp/pip-RaSccH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:19 INFO - Running setup.py (path:/tmp/pip-D4kEhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:19 INFO - Running setup.py (path:/tmp/pip-ToQVwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:19 INFO - Running setup.py (path:/tmp/pip-QlFHjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:19 INFO - Running setup.py (path:/tmp/pip-nl2_3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:19 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)) 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:19 INFO - Running setup.py (path:/tmp/pip-OmZfOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:20 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)) 09:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:20 INFO - Running setup.py (path:/tmp/pip-IPr7D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:20 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)) 09:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:20 INFO - Running setup.py (path:/tmp/pip-v0sNGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:20 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)) 09:13:20 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)) 09:13:20 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)) 09:13:20 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)) 09:13:20 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)) 09:13:20 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)) 09:13:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:20 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 09:13:20 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 09:13:20 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 09:13:20 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 09:13:20 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 09:13:20 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 09:13:22 INFO - Downloading blessings-1.6.tar.gz 09:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:22 INFO - Installing collected packages: blessings 09:13:22 INFO - Running setup.py install for blessings 09:13:22 INFO - Successfully installed blessings 09:13:22 INFO - Cleaning up... 09:13:22 INFO - Return code: 0 09:13:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:22 INFO - Reading from file tmpfile_stdout 09:13:22 INFO - Current package versions: 09:13:22 INFO - argparse == 1.2.1 09:13:22 INFO - blessings == 1.6 09:13:22 INFO - blobuploader == 1.2.4 09:13:22 INFO - docopt == 0.6.1 09:13:22 INFO - functools32 == 3.2.3-2 09:13:22 INFO - jsonschema == 2.5.1 09:13:22 INFO - manifestparser == 1.1 09:13:22 INFO - mozInstall == 1.12 09:13:22 INFO - mozcrash == 0.17 09:13:22 INFO - mozdebug == 0.1 09:13:22 INFO - mozdevice == 0.48 09:13:22 INFO - mozfile == 1.2 09:13:22 INFO - mozhttpd == 0.7 09:13:22 INFO - mozinfo == 0.9 09:13:22 INFO - mozleak == 0.1 09:13:22 INFO - mozlog == 3.2 09:13:22 INFO - moznetwork == 0.27 09:13:22 INFO - mozprocess == 0.22 09:13:22 INFO - mozprofile == 0.28 09:13:22 INFO - mozrunner == 6.11 09:13:22 INFO - mozscreenshot == 0.1 09:13:22 INFO - mozsystemmonitor == 0.3 09:13:22 INFO - moztest == 0.7 09:13:22 INFO - mozversion == 1.4 09:13:22 INFO - psutil == 3.1.1 09:13:22 INFO - requests == 1.2.3 09:13:22 INFO - wsgiref == 0.1.2 09:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:22 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 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13:22 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 09:13:22 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 09:13:22 INFO - Using env: {'DISPLAY': ':0', 09:13:22 INFO - 'HOME': '/home/cltbld', 09:13:22 INFO - 'LANG': 'en_US.UTF-8', 09:13:22 INFO - 'LANGUAGE': 'en_US:en', 09:13:22 INFO - 'LOGNAME': 'cltbld', 09:13:22 INFO - 'MAIL': '/var/mail/cltbld', 09:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:22 INFO - 'MOZ_NO_REMOTE': '1', 09:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:22 INFO - 'NO_EM_RESTART': '1', 09:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:22 INFO - 'PWD': '/builds/slave/test', 09:13:22 INFO - 'SHELL': '/bin/bash', 09:13:22 INFO - 'SHLVL': '1', 09:13:22 INFO - 'TERM': 'linux', 09:13:22 INFO - 'TMOUT': '86400', 09:13:22 INFO - 'USER': 'cltbld', 09:13:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:22 INFO - '_': '/tools/buildbot/bin/python'} 09:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 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)) 09:13:22 INFO - Cleaning up... 09:13:22 INFO - Return code: 0 09:13:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13: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', 'jsonschema'] in /builds/slave/test/build 09:13: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 jsonschema 09:13:22 INFO - Using env: {'DISPLAY': ':0', 09:13:22 INFO - 'HOME': '/home/cltbld', 09:13:22 INFO - 'LANG': 'en_US.UTF-8', 09:13:22 INFO - 'LANGUAGE': 'en_US:en', 09:13:22 INFO - 'LOGNAME': 'cltbld', 09:13:22 INFO - 'MAIL': '/var/mail/cltbld', 09:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:22 INFO - 'MOZ_NO_REMOTE': '1', 09:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:22 INFO - 'NO_EM_RESTART': '1', 09:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:22 INFO - 'PWD': '/builds/slave/test', 09:13:22 INFO - 'SHELL': '/bin/bash', 09:13:22 INFO - 'SHLVL': '1', 09:13:22 INFO - 'TERM': 'linux', 09:13:22 INFO - 'TMOUT': '86400', 09:13:22 INFO - 'USER': 'cltbld', 09:13:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:22 INFO - '_': '/tools/buildbot/bin/python'} 09:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:13:22 INFO - Cleaning up... 09:13:22 INFO - Return code: 0 09:13:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f067805df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1143350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, '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': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', '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 09:13: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', 'flake8'] in /builds/slave/test/build 09:13: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 flake8 09:13:22 INFO - Using env: {'DISPLAY': ':0', 09:13:22 INFO - 'HOME': '/home/cltbld', 09:13:22 INFO - 'LANG': 'en_US.UTF-8', 09:13:22 INFO - 'LANGUAGE': 'en_US:en', 09:13:22 INFO - 'LOGNAME': 'cltbld', 09:13:22 INFO - 'MAIL': '/var/mail/cltbld', 09:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:22 INFO - 'MOZ_NO_REMOTE': '1', 09:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:22 INFO - 'NO_EM_RESTART': '1', 09:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:22 INFO - 'PWD': '/builds/slave/test', 09:13:22 INFO - 'SHELL': '/bin/bash', 09:13:22 INFO - 'SHLVL': '1', 09:13:22 INFO - 'TERM': 'linux', 09:13:22 INFO - 'TMOUT': '86400', 09:13:22 INFO - 'USER': 'cltbld', 09:13:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:22 INFO - '_': '/tools/buildbot/bin/python'} 09:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:23 INFO - Downloading/unpacking flake8 09:13:23 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 09:13:23 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 09:13:23 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 09:13:23 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 09:13:23 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 09:13:23 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 09:13:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:13:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:13:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 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 09:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:13:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:25 INFO - no previously-included directories found matching 'docs/_build' 09:13:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:13:25 INFO - Running setup.py install for pep8 09:13:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:25 INFO - no previously-included directories found matching 'docs/_build' 09:13:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:13:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:13:25 INFO - Cleaning up... 09:13:26 INFO - Return code: 0 09:13:26 INFO - Running post-action listener: _resource_record_post_action 09:13:26 INFO - Running post-action listener: _start_resource_monitoring 09:13:26 INFO - Starting resource monitoring. 09:13:26 INFO - ##### 09:13:26 INFO - ##### Finished create-virtualenv step (success) 09:13:26 INFO - ##### 09:13:26 INFO - ##### 09:13:26 INFO - ##### Running install step. 09:13:26 INFO - ##### 09:13:26 INFO - Running pre-action listener: _resource_record_pre_action 09:13:26 INFO - Running main action method: install 09:13:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:26 INFO - Reading from file tmpfile_stdout 09:13:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:26 INFO - Reading from file tmpfile_stdout 09:13:26 INFO - Output received: 09:13:26 INFO - Usage: mozinstall [options] installer 09:13:26 INFO - Options: 09:13:26 INFO - -h, --help show this help message and exit 09:13:26 INFO - -d DEST, --destination=DEST 09:13:26 INFO - Directory to install application into. [default: 09:13:26 INFO - "/builds/slave/test"] 09:13:26 INFO - --app=APP Application being installed. [default: firefox] 09:13:26 INFO - mkdir: /builds/slave/test/build/application 09:13:26 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'] 09:13:26 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 09:13:42 INFO - Reading from file tmpfile_stdout 09:13:42 INFO - Output received: 09:13:42 INFO - /builds/slave/test/build/application/firefox/firefox 09:13:42 INFO - Running post-action listener: _resource_record_post_action 09:13:42 INFO - ##### 09:13:42 INFO - ##### Finished install step (success) 09:13:42 INFO - ##### 09:13:42 INFO - ##### 09:13:42 INFO - ##### Running run-tests step. 09:13:42 INFO - ##### 09:13:42 INFO - Running pre-action listener: _resource_record_pre_action 09:13:42 INFO - Running main action method: run_tests 09:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:42 INFO - Python 2.7.3 09:13:42 INFO - Return code: 0 09:13:42 INFO - grabbing minidump binary from tooltool 09:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:42 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 0x11d21c0>, '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 0x11d30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d3550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:42 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 09:13:42 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 09:13:42 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 09:13:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:42 INFO - Return code: 0 09:13:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:13:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:13:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:13:43 INFO - Return code: 0 09:13:43 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/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 09:13:43 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/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 09:13:43 INFO - Using env: {'DISPLAY': ':0', 09:13:43 INFO - 'HOME': '/home/cltbld', 09:13:43 INFO - 'LANG': 'en_US.UTF-8', 09:13:43 INFO - 'LANGUAGE': 'en_US:en', 09:13:43 INFO - 'LOGNAME': 'cltbld', 09:13:43 INFO - 'MAIL': '/var/mail/cltbld', 09:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:43 INFO - 'MOZ_NO_REMOTE': '1', 09:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:43 INFO - 'NO_EM_RESTART': '1', 09:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:43 INFO - 'PWD': '/builds/slave/test', 09:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:43 INFO - 'SHELL': '/bin/bash', 09:13:43 INFO - 'SHLVL': '1', 09:13:43 INFO - 'TERM': 'linux', 09:13:43 INFO - 'TMOUT': '86400', 09:13:43 INFO - 'USER': 'cltbld', 09:13:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986', 09:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:43 INFO - '_': '/tools/buildbot/bin/python'} 09:13:43 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/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 09:13:43 INFO - mozversion application_buildid: 20160623063124 09:13:43 INFO - mozversion application_changeset: d4e7738bd2aa5a523ca2551c467458c9f3eac45a 09:13:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:43 INFO - mozversion application_name: Firefox 09:13:43 INFO - mozversion application_remotingname: firefox-dev 09:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:13:43 INFO - mozversion application_vendor: Mozilla 09:13:43 INFO - mozversion application_version: 49.0a2 09:13:43 INFO - mozversion platform_buildid: 20160623063124 09:13:43 INFO - mozversion platform_changeset: d4e7738bd2aa5a523ca2551c467458c9f3eac45a 09:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:13:43 INFO - mozversion platform_version: 49.0a2 09:13:43 INFO - using testdate: 1466698423 09:13:43 INFO - actual date: 1466698423 09:13:43 INFO - starting webserver on 'localhost:33071' 09:13:43 INFO - SUITE-START | Running 2 tests 09:13:43 INFO - TEST-START | dromaeo_css 09:13:43 INFO - operating with platform_type : linux_ 09:13:43 INFO - Initialising browser for dromaeo_css test... 09:13:43 INFO - TEST-INFO | started process 3303 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9F0jp/profile http://localhost:33071/getInfo.html) 09:13:49 INFO - PROCESS | 3303 | __metrics Screen width/height:1600/1200 09:13:49 INFO - PROCESS | 3303 | colorDepth:24 09:13:49 INFO - PROCESS | 3303 | Browser inner width/height: 1024/697 09:13:49 INFO - PROCESS | 3303 | __metrics 09:13:50 ERROR - PROCESS | 3303 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:13:51 INFO - PROCESS | 3303 | console.error: PushService: 09:13:51 INFO - PROCESS | 3303 | stateChangeProcessEnqueue: Error transitioning state 09:13:51 INFO - PROCESS | 3303 | UnknownError 09:13:51 INFO - TEST-INFO | 3303: exit 0 09:13:51 INFO - Browser initialized. 09:13:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:51 INFO - TEST-INFO | started process 3395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9F0jp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:03 INFO - PROCESS | 3395 | RSS: Main: 145682432 09:14:03 INFO - PROCESS | 3395 | 09:14:55 INFO - PROCESS | 3395 | 1466698495004 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] 09:17:03 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33071/tests/dromaeo/cssquery-ext.html) 09:17:04 INFO - PROCESS | 3395 | RSS: Main: 257478656 09:17:04 INFO - PROCESS | 3395 | 09:20:06 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-ext.html (next: http://localhost:33071/tests/dromaeo/cssquery-jquery.html) 09:20:07 INFO - PROCESS | 3395 | RSS: Main: 234696704 09:20:07 INFO - PROCESS | 3395 | 09:23:08 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33071/tests/dromaeo/cssquery-mootools.html) 09:23:09 INFO - PROCESS | 3395 | RSS: Main: 225951744 09:23:09 INFO - PROCESS | 3395 | 09:26:11 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33071/tests/dromaeo/cssquery-prototype.html) 09:26:12 INFO - PROCESS | 3395 | RSS: Main: 221302784 09:26:12 INFO - PROCESS | 3395 | 09:29:14 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33071/tests/dromaeo/cssquery-yui.html) 09:29:15 INFO - PROCESS | 3395 | RSS: Main: 197443584 09:29:15 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:33071/tests/dromaeo/cssquery-yui.html (next: http://localhost:33071/tests/dromaeo/cssquery-dojo.html) 09:32:17 INFO - PROCESS | 3395 | RSS: Main: 260300800 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | __start_tp_report 09:32:17 INFO - PROCESS | 3395 | _x_x_mozilla_page_load 09:32:17 INFO - PROCESS | 3395 | _x_x_mozilla_page_load_details 09:32:17 INFO - PROCESS | 3395 | |i|pagename|runs| 09:32:17 INFO - PROCESS | 3395 | |0;dojo.html;3377.7128944039755;3391.7286617070636;3400.166959095022;3414.8292585370727;3431.2794313194227;5610.747516361764;5689.800856970001;5695.231143795586;5706.800261990831;5726.541876649873;5617.354004289508;5676.432356764331;5680;5682.602217844753;5687.886242275152;4911.975440122795;4970.15507363749;5096.3374761281075;5098.337216161894;5129.28190053391;5038.622103342245;5075.695458272516;5080.288759573663;5085.237214417848;5087.287779710843;5021.27191557224;5070.518300761421;5107.978404319132;5123.666961647499;5139.460356662553;5197.0125676121415;5198.740062996872;5215.739213039352;5218.999999999981;5232.215167724834;4906.31311616374;5012.924806127911;5086.237064440326;5108.591312694975;5118.6416950813455;5444.210589464545;5460.290162278918;5464.3442786865435;5473.2063850741815;5534.501894829485;5995;6001.639901605918;6027.638341699512;6030.306514750816;6035.456808887221;5992;6038.516918646497;6059.0608455689435;6067.605605635619;6113.694315284218;5710.400407957188;5719.5996280260815;5734.856628584277;5743.971280143573;5757.222774925373;5948.167256584082;6252.5935814171935;6255.311915689271;6257.311695713468;6257.7184026718905;5912.231409916683;5956.493698035629;5961.5826892118;6062.969295219823;6077.270727512639;6100.356533716556;6111.537044596885;6112.23692137642;6116.816495505142;6131.049687298476;5743.913841292384;5979.19280897082;6084.969575152096;6095.939040609537;6104.755809767559;6017.729202185825;6077.270727512727;6108.076676490625;6108.725107370179;6130.693465326716;4638.721676699409;4640.83757068501;4645.256758918556;4647.976760116246;4677.134730074947;4007.338789099824;4074.694397920168;4100.630943215125;4147.834086636561;4178.185253875526;5781.624194427349;5794.188813566104;5803.361630220672;5807.332156801937;5826.067829147316;6133.4012270796065;6134.259836200362;6134.938650613526;6142.938570614326;6148.354422785633;5527.917081243784;5701.4013528579735;5753.798617048467;5766;5800.100984347433;5260.763265653055;5262.105442074857;5265.868353291198;5268.130758424817;5272.499112584298;6344.460720838688;6350.682465876687;6354.586951848208;6355.936440635627;6363.522735794834;5868.589198756217;6028.306744724413;6096;6096.177016102769;6158.137860699416;6309.716062777278;6316;6358.586691865199;6367.968160159169;6373.426391624776;5778.133280008033;6010.489108425658;6017.398260173947;6021.397860213943;6021.638701677914;5754.884902302014;6030.668313242813;6048.274207095177;6153.907691384722;6154.815355539341;5966.820995370146;6001.669908154919;6003.249593800775;6007.459328660441;6009.699515024144;5668.2064510967675;5880.852978675396;5897.410258974069;5938.465538101592;5947.9702601486715;5858.443447872445;5960.552958528041;5963.671998040149;5973.163757073926;5988.191594134736;6040.909386359121;6060.363661815446;6060.696965151813;6074.577489034846;6088.3911608840535;5574.358948720786;5764.798232061858;5835.766569337349;5925.40745925404;5964.463198312173 09:32:17 INFO - PROCESS | 3395 | |1;ext.html;613.5490414545309;631.3560168628;655.9672016399179;656.6815094679076;670.7685848382309;34190.658093419086;46365.536344636545;47129.293060604075;47264.29103563441;47287.527124728746;48823.51176488234;49306.26040609385;49407;49460;49524.50475495244;8517.914820851805;9173.63305467782;9332.113449222314;10968.122550196005;11276.887231127706;10919.235653504258;11023.83464248037;11028.28316159451;11031.117510599173;11037.558497660084;11109;11133.498992545356;11168.66494005181;11177;11191.888081119207;8492.915070849274;11018;11250.268732532359;11270.042046426064;11297;10712.303700259536;10787.406692631903;10794.838077428845;11108.111351091893;11124.721881953015;46372;47056.76471617654;47070.76464617689;47167;47261.29108063382;3285.4250506161566;3293.901182964515;3602.189507360823;3736.40217565191;4234.830606775725;33477.83261083679;34147.4877876832;34153;36090.81954590236;38156.80921595374;4818.051232942288;4847.854564363075;4928.605711543068;4946.505349465025;4965.950340496585;400526;401028;402683;402821;404385;39634.40548391776;40141.79929100336;40181.196376072316;72615;73291;42482.875856207065;42600.360994585106;42733.57266427296;42744.14511709748;42746.35880461796;13563;15466.9226653866;15521.534353969173;15675.216239187996;15681.68636627261;12617.558385456465;12948.287844168473;12952.676183095498;12993.960483161325;13096.934515327363;9535.761605959768;9572.712818615453;9598.520073996273;9646.228301735844;9657.082577155159;3616.349057169735;3667.1015601177883;3703.574088979748;3727.9440808387894;3758.3047136279874;1096.6733910842077;1102.1403305421784;1144.3420033480782;1172.2966220267772;1270.574357590199;34461.827690861384;34841.825790870884;35080.82459587686;35131;35191.824040879634;1163.552032467506;1182.869884312725;1193.242291145129;1263.570386068741;1283.4737757519372;17717.379891703727;18347.449576512725;18392.816071839377;18452.26190952347;18494.907525462557;600501;606012;608300;608506;608563;64302.67848660727;64439.67780161069;64756.35243647597;65034.67482662557;65177.674111630084;1289.7420515896858;1310.3710219094887;1373.601655519908;1401.6776141487514;1411.9364628591738;31800;35177.47233791546;35288.11779705528;35695.46456803202;35740.82129589335;62981.37018629755;63880.36119638744;64024.35975640184;64312.35687643064;64389.35610644021;559517;560725;565298;565536;565744;49310.50689493203;50512.24231636602;51233.23150152825;51445.22832157595;51925.18261860817;55198;55859.44140558542;55968;56276.71861640666;56525.15212271737;45099.774501127285;45339;47201.29198061963;47286.76356618195;47623.28565071458;44907.77546112249;45760.54239457563;45820.54179458163;46449.53550464452;46774.766126169154;45199.77400112979;46454;46832;46939.530604693515;47138.76430617825;39332.606673932896;40831.38752918768;41192.79403602963;41303.7934810324;41335;30661.23346916345;30845.845770771004;30893.38213235678;31105.222369440944;31338 09:32:17 INFO - PROCESS | 3395 | |2;jquery.html;429.4588818089207;433.7635988386329;433.8850204695754;436.83181974939646;438.9912201755965;19102.713459298106;20339.694904576405;20877.686834697473;21646.24238151665;21648.567028659418;19809.900950495226;20285.594288114266;20508.69236961447;20531.692024619606;20575.485612859688;5587.525060369863;5775.826725198251;5797.768089276445;5807.970960145193;5818.767249310023;5677.829665110033;5729.914051289234;5792.131180322965;5794.130880367937;5795.130730390433;5726.083826587746;5729.828105156852;5729.856753581173;5735.827925162252;5747.195392645033;5645.887082258352;5773.682447465388;5776.682282474462;5779.017567013618;5779.162021506879;4985.351904252424;5076.771545280434;5080.771365288534;5088.287639730441;5103.336566246401;15182.392704291815;15676.451324203597;22504.662430063563;23291.650625240636;23298.65052024221;2228.74369447514;2262.3778460923213;2331.9067237310487;2485.3538080099306;3000.7149320814488;11147.832782508187;12146.514139434412;13685.315734213249;15088.849111508964;15286.159261240628;1988.532694816717;2010.6581881080251;2065.731454910867;2088.937331880046;2089.2896415218747;80674.59662701708;82196.58901705453;84483;85486;86078.99999999937;11585.884141158649;11638.974950642347;11742.412879356169;11855.347955862338;11901.583444579399;16453.83546164547;16618.169091545373;16623.6675266494;16631.41790037367;16650.167491625612;8229.506229626122;8299.128591497927;8311.750647480585;8366.623501942306;8406.495610263404;7790.883136752953;7855.096663883613;7920.960395198103;7992.400569957276;8196.83606327882;5027.497250274943;5039.470855560114;5103;5114.616403769658;5169.81905633301;2089.3000844716885;2117.4918019675165;2139.539998900244;2147.2484630379554;2164.4372463159375;883.5935469683889;885.2696525366598;952.3761935931975;991.965281215154;1058.4654749351528;18667.15997780076;18976.620467590572;19260.422187334403;19496.610067798563;19764.70352944707;1024.6721049264163;1026.5329275179774;1067.530286673861;1082.4263140535372;1113.5267511307661;10623.309484883457;10755.731106722238;10851;10857.619983300547;10931.836022459669;393373;394513;396414;487336;491699;95293;101440;101530;101700.49149754204;101910;1110.1174566219875;1117.407773879845;1132.7847708935276;1134.9546018159347;1145.495981768036;17488.650226995644;17720.911395443454;17748.29006839712;17769.289228430716;17845.196966136296;29788.32661001665;40526.1894762109;42611.7869410651;43411.34882976821;43490.782546087066;58540.414595853494;59477.40522594892;59512.70243648927;60137.39862601318;60177;7706.190849960673;8194.549299788569;8269.669213231404;8388.257037956068;9075.410098343587;7057.258987806412;9323.860142097737;12274.202176858476;13197.142185757895;13728.244946528037;11421.828672570084;11463.656090317303;11475.885241147816;11556.711082223012;11854.762904742029;10526.842097368699;10957.890421096006;11240.494177761857;11260.324380537195;11602.70993225176;10689.25175237757;11189.440527973406;11209.159313051718;11462.598809041643;11610;8407.318536292643;8413.789655258668;8422.24199822019;8551.358648101294;8654.177853103945;11423.657290281304;11491.655250342503;11534.82697759551;11552.306861588331;11842.703932401759 09:32:17 INFO - PROCESS | 3395 | |3;mootools.html;1367.7469668111394;1562.679650671612;1581.2568092996291;1776.9733453998185;1922.6154769046202;9345.252379809615;9672.66145684902;9707.660231891876;9947.104760571565;9981.251406144533;9727;9892.109710126088;9894.50527473627;9909.554070066846;9932.106110450059;2159.0608085482786;2211.2592281585607;2484.664570283007;2527.128140791433;2554.118829003991;2520.1935380678096;2538.340031591781;2540.3649087728068;2540.8475491470476;2545.6690630218045;2457.3979375053136;2538.0355464923305;2538.3019669590917;2538.6671997201433;2539.4032402385424;2139.946501337471;2392.7248366437907;2406.879656017201;2464.9753502464923;2467.864267465289;2459.754024597526;2469.6295555666616;2474.072222916406;2491.5888878335054;2493.0775613023116;9979.750506237402;10055.446950417723;10058.04448577384;10058.396496210251;10060.346077504939;5857.4435428634215;6000.699965001776;6001.609895356788;6003.879922401527;6010.57925945184;3617.981910090459;3733.775973441575;3753.7372383933134;3807.8857634271017;3868.613138686137;1157.2940506291156;1167.3987896233446;1200.9399530023463;1226.760781647579;1231.7536492701495;14403.495877644336;14444;14478.56564303062;14491.492797752133;14510.419583216659;5198.32421785173;5209.010288045335;5257.290265814143;5303.549198318109;5321.068812957717;11765.470553825098;11826.172167948162;11841;11858.94070529659;11901.76196476083;5870.501007414332;5922.644641321448;5928.792492262836;5929;5930.703464826742;4731.077439899255;5068.898622027539;5231.581473482112;5233.607479439057;5336.759845806948;4503.2569626012;4522.638188944877;4575.656825738017;4598.724076555417;4609.446866376056;1388.0699931046224;1417.340936464549;1432.6919712261913;1438.0724432740824;1442.942282308696;1048.2400259811727;1070.7858428314369;1071.2501249125644;1072.8176210044155;1088.553692985872;6549.377809108127;6730.899036514456;6735.42748866342;6788.694508747119;6823.078884350549;467.9836205732783;476.39973633221695;487.939007624047;492.4607554727568;492.74623568862063;4560.840370587013;4642.280446530793;4661.277501987197;4725.243960966229;4756.9524304756505;12615.558455454015;12882.80675789864;12955.611331660066;12958;12976.610701678965;12188.939055304667;12572.182808117443;12584.81122783159;12978.870211297955;12993.870061299454;9197.908020919838;9412.105849944244;9412.105849944244;9419.71740847762;9420.293477989178;6603.636799975855;6859.965700171667;6931.376176144171;7007.089078419773;7021.227664956748;11612.477438515118;11614;11714.531418743154;11957.701057473632;12034.458449369975;12038.458269377725;12080.818787718366;12150.513979440724;12250.264984100626;12298.938505307417;8039.718609848627;8638.956805215934;8834.514101724468;8946.597403116744;9104.635814567608;9991.550380233053;10000;10029.648962286285;10095.7476063099;10098.141657959155;8530.530820804915;8724.996625388161;8829.779255518664;8893.28853691716;8938.061503542034;8264.090949995616;8320.70877519284;8428.241458268785;8448.028476725256;8548.700795472128;7631.847363052597;7698.576578288248;7765.961170194113;7780;7787.143414224318;5535.169724541351;5629.549636029026;5778.797742079007;5869.706514674334;5894;5692.1461780733225;5697.4587414195585;5722.284714410699;5736.913946290892;5812.476877081083 09:32:17 INFO - PROCESS | 3395 | |4;prototype.html;348.445970906259;358.2119337457594;362.35139101009196;364.34599893191717;367.4011361480786;5542.612017158801;5546;5578.302712160975;5585.8603534911645;5602.8319150425505;5256.448072952342;5376.731163441822;5376.865578360543;5387.299651045369;5514.476124768151;4180.749155050691;4247.088810035489;4263.593014305287;4290.206311832321;4320.351947207928;4259.5740425957465;4284.207421626993;4288.549702281247;4293.65650747941;4317.503487098992;4083.9591604083876;4240.180280113721;4244;4258.91482170358;4319.503257125423;4233.047564298039;4239.5548467410945;4244.469441319835;4256.616904478597;4278.55075217104;3763.8118094095184;3766.1902690921584;3776.4146557283666;3781.1870447853566;3785.9053523661855;5210.505002024802;5252.030488566787;5295.391030031557;5310.7610157543;5351.197320401932;4854.732989685566;4863.076015557032;4868;4882.170031094723;4892.828750993698;5714.428557144252;5725.826205627836;5786.971065144648;5791.420857914175;5795.1017592273265;3957.129431525089;4122.484689413824;4144.79276036197;4153.771542565158;4157.750534967911;5842.824715258549;5900.498457631106;5935.169076329317;5958.821235362946;5964;4244.448221731217;4251.213525497793;4262.296721041055;4263.381809637585;4290.377895205178;4139.523954745183;4264.466941632296;4266.31738921771;4278.251306021434;4320.157569273962;4787.760611969387;4821.783019764119;4845.685030473008;4847.709137451764;4851.126797176472;5724.656520608777;5735.741891614887;5744.109663002241;5804.2744656917885;5811.3316968548315;1427.3719563392074;1469.4563011685746;1474.4544518528214;1481.8811797093342;1495.3121564080427;942.9156470059487;953.3993584042009;963.889152747429;969.5588507229194;971.4317124482257;5059.696418214845;5064.18972964324;5075.467075957045;5076.492350764968;5082.822101226513;5270.314859068373;5297.629165958423;5318.893622127536;5327.254184414185;5329.600279979017;4669.276242211205;4955.603551715854;5060.886604946906;5086.3133476980875;5090.26191202281;2794.413173233603;2808.606795048695;2827.448647513715;2833.9656025550526;2857.5570786527705;5374.247605335257;5378.946210537844;5421.945780542223;5441.5851878511085;5444.891102177934;5285.097364948615;5320.813771518056;5604.747786349624;6073.1497590337385;6074.088886666947;6032.366601506867;6071.907056729743;6074.787382441682;6172.629642221481;6175.536834737412;5655.660660360391;5894;5894.498967587803;5907.995640740998;5953.345132035559;5812.447817457335;5830.737616807339;5846.269216347957;5851.561132914963;5859.267591551056;6159.844054580861;6160.014397696347;6161.383861613803;6186.845328866814;6190.823743488722;4034.1931613676793;4155.688323375698;4167.229062623424;4254.574542545721;4287.121140166196;5855.765769369177;5878.382769809279;5890.705464726832;5904.734286957011;5919.437653422918;5769.192313076089;5769.644133628678;5770.596058275881;5783.595148339744;5802.94197058024;5615.427680249537;5686.6400443815355;5702.543796496353;5720.8283751487525;5753.884922301447;5636.267285252891;5638.689872057076;5643.435656434488;5655.717214139194;5658.886822263614;5583.637063590853;5737.311522617312;5780.826575202751;5782.739776709974;5783.710814459176;5603.982566276219;5636.633618814764;5644.887102258014;5647.181158731879;5672.205891175321 09:32:17 INFO - PROCESS | 3395 | |5;yui.html;799.9480033797803;808.6846130009294;811.1766556944702;825.3768404854334;893.5621545442727;15665.451709190167;17459;18190.27238910445;18227.088645567736;18244.361447349347;15817.762733558977;16359.345626174938;16512.422065227718;16520;17032.82967170328;892.8392889279927;893.6604090445621;898.8561830107184;899.8515244984602;900.2977677411629;853.4623187391934;854.4360721923532;855.6064210463195;855.7389996051202;867.839449701804;945.7730144765276;953.2469349214113;953.4946478366477;954.308126608207;955.1642312976146;948.8244674735162;950.1353768071023;950.6435086842434;950.828850806858;952.2286947572488;699.5033526196406;699.5313140196081;700.0444393403019;700.1563116444723;700.5586480517293;16475.17624118789;16491;16552.089635070068;16554.503364899072;16597.41909033178;15403.306851191723;15514.5345639631;15648.758424709511;15886.841131588651;16349.346026158939;12461.75076498474;12945.288009159493;12979.41592628334;13131.0808243423;13256.933715331457;3993.3011722948368;4078.9592104079175;4097.836086556535;4113.218488487208;4161.459010328638;67922.32077679259;68419.65790171018;68496;68711.31288687099;78154.21845781583;12026.879731202751;12350.567730129402;12373.75252494945;12441.066919981038;12594.433250503565;21515.67726484104;22415;22708.545829083327;22766.31701048971;22800.657990130163;9147.342346375326;9347.579358928728;9357.859632105525;9383.10860468268;9569.28230382724;4953.2074868020945;4999.625028122905;5031.069252188357;5121.411037730709;5124.256982737556;4009.69927255457;4183.518895327015;4189.120284740177;4205.6635469163;4210.494740631144;728.0498948871773;741.0514541387033;745.3999530377989;753.3182469864781;753.6570860258569;695.4193248637343;740.6481921087441;763.3892885691424;774.1136398823315;813.5647428625775;10603.522841472151;10801.70794533381;10946.343219406861;11020.614278500214;11066.944665276622;671.6473851228144;733.9211359301818;754.4379437319233;813.9511629302261;835.4820011592851;5033.949660503421;5123.7438128093445;5132.743362831843;5136.075506408883;5143.305653736773;101596.49201754092;112459;113558.43220783843;120566;120732;98787;99775.50112249538;101276.49361753292;102681.48659256805;102817.48591257146;51322.74338628283;76574.61712691512;87497.56251218703;90326.54836725906;94004.52997735105;10175.338602990782;10217.897821021992;10266.435346056038;10284.897151028694;10315.535800888827;53856.73071634617;55641;55810.44189558052;56781.71609141928;57368.13947790703;69421.30578694148;70278;70342.64828675824;77021.61489192519;77065;13648.590542283748;13922;13933.23367214813;13934.233617151154;14066.156030638194;15153.379712791331;16014;16120.758188627415;16178.352865885232;16267.186640668157;14214.373381963655;14452.494162704255;14588.489402870848;14655.120692758468;14710.852891470948;14041.859581404464;14249.515280443082;14903.925480372962;15109.773353399489;15410.537683869501;14886.851131488547;15088.622784430478;15331.923340383226;15791.21043947775;15804.841951580796;12299.385030748248;12546.811797822857;12560.560380386643;12829.486820527447;12960.935195323964;11962.222455540363;12235.434896417479;12309.384530773605;12529.561465348668;12656.43046062911 09:32:17 INFO - PROCESS | 3395 | __end_tp_report 09:32:17 INFO - PROCESS | 3395 | __start_cc_report 09:32:17 INFO - PROCESS | 3395 | _x_x_mozilla_cycle_collect,902 09:32:17 INFO - PROCESS | 3395 | __end_cc_report 09:32:17 INFO - PROCESS | 3395 | __startTimestamp1466699537719__endTimestamp 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | ------- Summary: start ------- 09:32:17 INFO - PROCESS | 3395 | Number of tests: 6 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#0] dojo.html Cycles:165 Average:5636.50 Median:5858.44 stddev:646.13 (11.0%) stddev-sans-first:623.34 09:32:17 INFO - PROCESS | 3395 | Values: 3377.7 3391.7 3400.2 3414.8 3431.3 5610.7 5689.8 5695.2 5706.8 5726.5 5617.4 5676.4 5680.0 5682.6 5687.9 4912.0 4970.2 5096.3 5098.3 5129.3 5038.6 5075.7 5080.3 5085.2 5087.3 5021.3 5070.5 5108.0 5123.7 5139.5 5197.0 5198.7 5215.7 5219.0 5232.2 4906.3 5012.9 5086.2 5108.6 5118.6 5444.2 5460.3 5464.3 5473.2 5534.5 5995.0 6001.6 6027.6 6030.3 6035.5 5992.0 6038.5 6059.1 6067.6 6113.7 5710.4 5719.6 5734.9 5744.0 5757.2 5948.2 6252.6 6255.3 6257.3 6257.7 5912.2 5956.5 5961.6 6063.0 6077.3 6100.4 6111.5 6112.2 6116.8 6131.0 5743.9 5979.2 6085.0 6095.9 6104.8 6017.7 6077.3 6108.1 6108.7 6130.7 4638.7 4640.8 4645.3 4648.0 4677.1 4007.3 4074.7 4100.6 4147.8 4178.2 5781.6 5794.2 5803.4 5807.3 5826.1 6133.4 6134.3 6134.9 6142.9 6148.4 5527.9 5701.4 5753.8 5766.0 5800.1 5260.8 5262.1 5265.9 5268.1 5272.5 6344.5 6350.7 6354.6 6355.9 6363.5 5868.6 6028.3 6096.0 6096.2 6158.1 6309.7 6316.0 6358.6 6368.0 6373.4 5778.1 6010.5 6017.4 6021.4 6021.6 5754.9 6030.7 6048.3 6153.9 6154.8 5966.8 6001.7 6003.2 6007.5 6009.7 5668.2 5880.9 5897.4 5938.5 5948.0 5858.4 5960.6 5963.7 5973.2 5988.2 6040.9 6060.4 6060.7 6074.6 6088.4 5574.4 5764.8 5835.8 5925.4 5964.5 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#1] ext.html Cycles:180 Average:69053.08 Median:41012.09 stddev:141323.52 (344.6%) stddev-sans-first:141626.55 09:32:17 INFO - PROCESS | 3395 | Values: 613.5 631.4 656.0 656.7 670.8 34190.7 46365.5 47129.3 47264.3 47287.5 48823.5 49306.3 49407.0 49460.0 49524.5 8517.9 9173.6 9332.1 10968.1 11276.9 10919.2 11023.8 11028.3 11031.1 11037.6 11109.0 11133.5 11168.7 11177.0 11191.9 8492.9 11018.0 11250.3 11270.0 11297.0 10712.3 10787.4 10794.8 11108.1 11124.7 46372.0 47056.8 47070.8 47167.0 47261.3 3285.4 3293.9 3602.2 3736.4 4234.8 33477.8 34147.5 34153.0 36090.8 38156.8 4818.1 4847.9 4928.6 4946.5 4966.0 400526.0 401028.0 402683.0 402821.0 404385.0 39634.4 40141.8 40181.2 72615.0 73291.0 42482.9 42600.4 42733.6 42744.1 42746.4 13563.0 15466.9 15521.5 15675.2 15681.7 12617.6 12948.3 12952.7 12994.0 13096.9 9535.8 9572.7 9598.5 9646.2 9657.1 3616.3 3667.1 3703.6 3727.9 3758.3 1096.7 1102.1 1144.3 1172.3 1270.6 34461.8 34841.8 35080.8 35131.0 35191.8 1163.6 1182.9 1193.2 1263.6 1283.5 17717.4 18347.4 18392.8 18452.3 18494.9 600501.0 606012.0 608300.0 608506.0 608563.0 64302.7 64439.7 64756.4 65034.7 65177.7 1289.7 1310.4 1373.6 1401.7 1411.9 31800.0 35177.5 35288.1 35695.5 35740.8 62981.4 63880.4 64024.4 64312.4 64389.4 559517.0 560725.0 565298.0 565536.0 565744.0 49310.5 50512.2 51233.2 51445.2 51925.2 55198.0 55859.4 55968.0 56276.7 56525.2 45099.8 45339.0 47201.3 47286.8 47623.3 44907.8 45760.5 45820.5 46449.5 46774.8 45199.8 46454.0 46832.0 46939.5 47138.8 39332.6 40831.4 41192.8 41303.8 41335.0 30661.2 30845.8 30893.4 31105.2 31338.0 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#2] jquery.html Cycles:180 Average:27778.86 Median:12366.70 stddev:72396.44 (585.4%) stddev-sans-first:72570.41 09:32:17 INFO - PROCESS | 3395 | Values: 429.5 433.8 433.9 436.8 439.0 19102.7 20339.7 20877.7 21646.2 21648.6 19809.9 20285.6 20508.7 20531.7 20575.5 5587.5 5775.8 5797.8 5808.0 5818.8 5677.8 5729.9 5792.1 5794.1 5795.1 5726.1 5729.8 5729.9 5735.8 5747.2 5645.9 5773.7 5776.7 5779.0 5779.2 4985.4 5076.8 5080.8 5088.3 5103.3 15182.4 15676.5 22504.7 23291.7 23298.7 2228.7 2262.4 2331.9 2485.4 3000.7 11147.8 12146.5 13685.3 15088.8 15286.2 1988.5 2010.7 2065.7 2088.9 2089.3 80674.6 82196.6 84483.0 85486.0 86079.0 11585.9 11639.0 11742.4 11855.3 11901.6 16453.8 16618.2 16623.7 16631.4 16650.2 8229.5 8299.1 8311.8 8366.6 8406.5 7790.9 7855.1 7921.0 7992.4 8196.8 5027.5 5039.5 5103.0 5114.6 5169.8 2089.3 2117.5 2139.5 2147.2 2164.4 883.6 885.3 952.4 992.0 1058.5 18667.2 18976.6 19260.4 19496.6 19764.7 1024.7 1026.5 1067.5 1082.4 1113.5 10623.3 10755.7 10851.0 10857.6 10931.8 393373.0 394513.0 396414.0 487336.0 491699.0 95293.0 101440.0 101530.0 101700.5 101910.0 1110.1 1117.4 1132.8 1135.0 1145.5 17488.7 17720.9 17748.3 17769.3 17845.2 29788.3 40526.2 42611.8 43411.3 43490.8 58540.4 59477.4 59512.7 60137.4 60177.0 7706.2 8194.5 8269.7 8388.3 9075.4 7057.3 9323.9 12274.2 13197.1 13728.2 11421.8 11463.7 11475.9 11556.7 11854.8 10526.8 10957.9 11240.5 11260.3 11602.7 10689.3 11189.4 11209.2 11462.6 11610.0 8407.3 8413.8 8422.2 8551.4 8654.2 11423.7 11491.7 11534.8 11552.3 11842.7 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#3] mootools.html Cycles:180 Average:6581.75 Median:4568.25 stddev:3906.27 (85.5%) stddev-sans-first:3897.57 09:32:17 INFO - PROCESS | 3395 | Values: 1367.7 1562.7 1581.3 1777.0 1922.6 9345.3 9672.7 9707.7 9947.1 9981.3 9727.0 9892.1 9894.5 9909.6 9932.1 2159.1 2211.3 2484.7 2527.1 2554.1 2520.2 2538.3 2540.4 2540.8 2545.7 2457.4 2538.0 2538.3 2538.7 2539.4 2139.9 2392.7 2406.9 2465.0 2467.9 2459.8 2469.6 2474.1 2491.6 2493.1 9979.8 10055.4 10058.0 10058.4 10060.3 5857.4 6000.7 6001.6 6003.9 6010.6 3618.0 3733.8 3753.7 3807.9 3868.6 1157.3 1167.4 1200.9 1226.8 1231.8 14403.5 14444.0 14478.6 14491.5 14510.4 5198.3 5209.0 5257.3 5303.5 5321.1 11765.5 11826.2 11841.0 11858.9 11901.8 5870.5 5922.6 5928.8 5929.0 5930.7 4731.1 5068.9 5231.6 5233.6 5336.8 4503.3 4522.6 4575.7 4598.7 4609.4 1388.1 1417.3 1432.7 1438.1 1442.9 1048.2 1070.8 1071.3 1072.8 1088.6 6549.4 6730.9 6735.4 6788.7 6823.1 468.0 476.4 487.9 492.5 492.7 4560.8 4642.3 4661.3 4725.2 4757.0 12615.6 12882.8 12955.6 12958.0 12976.6 12188.9 12572.2 12584.8 12978.9 12993.9 9197.9 9412.1 9412.1 9419.7 9420.3 6603.6 6860.0 6931.4 7007.1 7021.2 11612.5 11614.0 11714.5 11957.7 12034.5 12038.5 12080.8 12150.5 12250.3 12298.9 8039.7 8639.0 8834.5 8946.6 9104.6 9991.6 10000.0 10029.6 10095.7 10098.1 8530.5 8725.0 8829.8 8893.3 8938.1 8264.1 8320.7 8428.2 8448.0 8548.7 7631.8 7698.6 7766.0 7780.0 7787.1 5535.2 5629.5 5778.8 5869.7 5894.0 5692.1 5697.5 5722.3 5736.9 5812.5 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#4] prototype.html Cycles:180 Average:4761.39 Median:5328.43 stddev:1399.46 (26.3%) stddev-sans-first:1363.63 09:32:17 INFO - PROCESS | 3395 | Values: 348.4 358.2 362.4 364.3 367.4 5542.6 5546.0 5578.3 5585.9 5602.8 5256.4 5376.7 5376.9 5387.3 5514.5 4180.7 4247.1 4263.6 4290.2 4320.4 4259.6 4284.2 4288.5 4293.7 4317.5 4084.0 4240.2 4244.0 4258.9 4319.5 4233.0 4239.6 4244.5 4256.6 4278.6 3763.8 3766.2 3776.4 3781.2 3785.9 5210.5 5252.0 5295.4 5310.8 5351.2 4854.7 4863.1 4868.0 4882.2 4892.8 5714.4 5725.8 5787.0 5791.4 5795.1 3957.1 4122.5 4144.8 4153.8 4157.8 5842.8 5900.5 5935.2 5958.8 5964.0 4244.4 4251.2 4262.3 4263.4 4290.4 4139.5 4264.5 4266.3 4278.3 4320.2 4787.8 4821.8 4845.7 4847.7 4851.1 5724.7 5735.7 5744.1 5804.3 5811.3 1427.4 1469.5 1474.5 1481.9 1495.3 942.9 953.4 963.9 969.6 971.4 5059.7 5064.2 5075.5 5076.5 5082.8 5270.3 5297.6 5318.9 5327.3 5329.6 4669.3 4955.6 5060.9 5086.3 5090.3 2794.4 2808.6 2827.4 2834.0 2857.6 5374.2 5378.9 5421.9 5441.6 5444.9 5285.1 5320.8 5604.7 6073.1 6074.1 6032.4 6071.9 6074.8 6172.6 6175.5 5655.7 5894.0 5894.5 5908.0 5953.3 5812.4 5830.7 5846.3 5851.6 5859.3 6159.8 6160.0 6161.4 6186.8 6190.8 4034.2 4155.7 4167.2 4254.6 4287.1 5855.8 5878.4 5890.7 5904.7 5919.4 5769.2 5769.6 5770.6 5783.6 5802.9 5615.4 5686.6 5702.5 5720.8 5753.9 5636.3 5638.7 5643.4 5655.7 5658.9 5583.6 5737.3 5780.8 5782.7 5783.7 5604.0 5636.6 5644.9 5647.2 5672.2 09:32:17 INFO - PROCESS | 3395 | 09:32:17 INFO - PROCESS | 3395 | [#5] yui.html Cycles:180 Average:21214.97 Median:4088.40 stddev:29330.69 (717.4%) stddev-sans-first:29372.91 09:32:17 INFO - PROCESS | 3395 | Values: 799.9 808.7 811.2 825.4 893.6 15665.5 17459.0 18190.3 18227.1 18244.4 15817.8 16359.3 16512.4 16520.0 17032.8 892.8 893.7 898.9 899.9 900.3 853.5 854.4 855.6 855.7 867.8 945.8 953.2 953.5 954.3 955.2 948.8 950.1 950.6 950.8 952.2 699.5 699.5 700.0 700.2 700.6 16475.2 16491.0 16552.1 16554.5 16597.4 15403.3 15514.5 15648.8 15886.8 16349.3 12461.8 12945.3 12979.4 13131.1 13256.9 3993.3 4079.0 4097.8 4113.2 4161.5 67922.3 68419.7 68496.0 68711.3 78154.2 12026.9 12350.6 12373.8 12441.1 12594.4 21515.7 22415.0 22708.5 22766.3 22800.7 9147.3 9347.6 9357.9 9383.1 9569.3 4953.2 4999.6 5031.1 5121.4 5124.3 4009.7 4183.5 4189.1 4205.7 4210.5 728.0 741.1 745.4 753.3 753.7 695.4 740.6 763.4 774.1 813.6 10603.5 10801.7 10946.3 11020.6 11066.9 671.6 733.9 754.4 814.0 835.5 5033.9 5123.7 5132.7 5136.1 5143.3 101596.5 112459.0 113558.4 120566.0 120732.0 98787.0 99775.5 101276.5 102681.5 102817.5 51322.7 76574.6 87497.6 90326.5 94004.5 10175.3 10217.9 10266.4 10284.9 10315.5 53856.7 55641.0 55810.4 56781.7 57368.1 69421.3 70278.0 70342.6 77021.6 77065.0 13648.6 13922.0 13933.2 13934.2 14066.2 15153.4 16014.0 16120.8 16178.4 16267.2 14214.4 14452.5 14588.5 14655.1 14710.9 14041.9 14249.5 14903.9 15109.8 15410.5 14886.9 15088.6 15331.9 15791.2 15804.8 12299.4 12546.8 12560.6 12829.5 12960.9 11962.2 12235.4 12309.4 12529.6 12656.4 09:32:17 INFO - PROCESS | 3395 | -------- Summary: end -------- 09:32:17 INFO - PROCESS | 3395 | 09:32:18 INFO - TEST-INFO | 3395: exit 0 09:32:18 INFO - TEST-OK | dromaeo_css | took 1114929ms 09:32:18 INFO - TEST-START | kraken 09:32:18 INFO - operating with platform_type : linux_ 09:32:18 INFO - Initialising browser for kraken test... 09:32:18 INFO - TEST-INFO | started process 3588 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbJwNbp/profile http://localhost:33071/getInfo.html) 09:32:22 INFO - PROCESS | 3588 | __metrics Screen width/height:1600/1200 09:32:22 INFO - PROCESS | 3588 | colorDepth:24 09:32:22 INFO - PROCESS | 3588 | Browser inner width/height: 1024/697 09:32:22 INFO - PROCESS | 3588 | __metrics 09:32:23 ERROR - PROCESS | 3588 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:32:23 INFO - PROCESS | 3588 | console.error: PushService: 09:32:23 INFO - PROCESS | 3588 | stateChangeProcessEnqueue: Error transitioning state 09:32:23 INFO - PROCESS | 3588 | UnknownError 09:32:24 INFO - TEST-INFO | 3588: exit 0 09:32:24 INFO - Browser initialized. 09:32:24 INFO - Running cycle 1/1 for kraken test... 09:32:24 INFO - TEST-INFO | started process 3681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbJwNbp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:35 INFO - PROCESS | 3681 | RSS: Main: 145715200 09:32:35 INFO - PROCESS | 3681 | 09:32:38 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33071/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:39 INFO - PROCESS | 3681 | RSS: Main: 194834432 09:32:39 INFO - PROCESS | 3681 | 09:32:41 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33071/tests/kraken/driver.html?testName=audio-dft) 09:32:42 INFO - PROCESS | 3681 | RSS: Main: 194576384 09:32:42 INFO - PROCESS | 3681 | 09:32:45 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33071/tests/kraken/driver.html?testName=audio-fft) 09:32:45 INFO - PROCESS | 3681 | RSS: Main: 280195072 09:32:45 INFO - PROCESS | 3681 | 09:32:47 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33071/tests/kraken/driver.html?testName=audio-oscillator) 09:32:48 INFO - PROCESS | 3681 | RSS: Main: 188628992 09:32:48 INFO - PROCESS | 3681 | 09:32:50 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:51 INFO - PROCESS | 3681 | RSS: Main: 189689856 09:32:51 INFO - PROCESS | 3681 | 09:32:54 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33071/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:54 INFO - PROCESS | 3681 | RSS: Main: 223997952 09:32:54 INFO - PROCESS | 3681 | 09:32:58 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33071/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:58 INFO - PROCESS | 3681 | RSS: Main: 223850496 09:32:58 INFO - PROCESS | 3681 | 09:33:01 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33071/tests/kraken/driver.html?testName=json-parse-financial) 09:33:02 INFO - PROCESS | 3681 | RSS: Main: 223330304 09:33:02 INFO - PROCESS | 3681 | 09:33:03 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:04 INFO - PROCESS | 3681 | RSS: Main: 201031680 09:33:04 INFO - PROCESS | 3681 | 09:33:05 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:06 INFO - PROCESS | 3681 | RSS: Main: 202440704 09:33:06 INFO - PROCESS | 3681 | 09:33:08 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:09 INFO - PROCESS | 3681 | RSS: Main: 208150528 09:33:09 INFO - PROCESS | 3681 | 09:33:11 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:12 INFO - PROCESS | 3681 | RSS: Main: 216481792 09:33:12 INFO - PROCESS | 3681 | 09:33:14 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:15 INFO - PROCESS | 3681 | RSS: Main: 217862144 09:33:15 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:33071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33071/tests/kraken/driver.html?testName=ai-astar) 09:33:17 INFO - PROCESS | 3681 | RSS: Main: 260304896 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | __start_tp_report 09:33:17 INFO - PROCESS | 3681 | _x_x_mozilla_page_load 09:33:17 INFO - PROCESS | 3681 | _x_x_mozilla_page_load_details 09:33:17 INFO - PROCESS | 3681 | |i|pagename|runs| 09:33:17 INFO - PROCESS | 3681 | |0;ai-astar;110;104;110;109;109;109;109;110;109;111 09:33:17 INFO - PROCESS | 3681 | |1;audio-beat-detection;152;129;127;169;123;123;144;125;154;124 09:33:17 INFO - PROCESS | 3681 | |2;audio-dft;160;162;161;161;161;159;159;161;160;160 09:33:17 INFO - PROCESS | 3681 | |3;audio-fft;75;75;75;125;74;74;77;74;96;73 09:33:17 INFO - PROCESS | 3681 | |4;audio-oscillator;84;84;114;84;82;82;108;83;83;121 09:33:17 INFO - PROCESS | 3681 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;118 09:33:17 INFO - PROCESS | 3681 | |6;imaging-darkroom;145;146;146;145;147;146;145;146;145;146 09:33:17 INFO - PROCESS | 3681 | |7;imaging-desaturate;95;94;95;95;95;94;94;95;94;95 09:33:17 INFO - PROCESS | 3681 | |8;json-parse-financial;50;49;49;51;50;52;53;51;51;51 09:33:17 INFO - PROCESS | 3681 | |9;json-stringify-tinderbox;65;65;65;65;65;64;64;65;65;65 09:33:17 INFO - PROCESS | 3681 | |10;stanford-crypto-aes;76;75;75;81;77;77;78;78;107;82 09:33:17 INFO - PROCESS | 3681 | |11;stanford-crypto-ccm;127;122;115;115;117;115;141;136;137;118 09:33:17 INFO - PROCESS | 3681 | |12;stanford-crypto-pbkdf2;156;158;156;154;157;157;155;156;154;157 09:33:17 INFO - PROCESS | 3681 | |13;stanford-crypto-sha256-iterative;62;61;62;61;62;61;62;61;60;60 09:33:17 INFO - PROCESS | 3681 | __end_tp_report 09:33:17 INFO - PROCESS | 3681 | __start_cc_report 09:33:17 INFO - PROCESS | 3681 | _x_x_mozilla_cycle_collect,1541 09:33:17 INFO - PROCESS | 3681 | __end_cc_report 09:33:17 INFO - PROCESS | 3681 | __startTimestamp1466699597499__endTimestamp 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | ------- Summary: start ------- 09:33:17 INFO - PROCESS | 3681 | Number of tests: 14 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#0] ai-astar Cycles:10 Average:109.00 Median:109.50 stddev:1.89 (1.7%) stddev-sans-first:1.96 09:33:17 INFO - PROCESS | 3681 | Values: 110.0 104.0 110.0 109.0 109.0 109.0 109.0 110.0 109.0 111.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:136.50 stddev:16.52 (12.1%) stddev-sans-first:16.61 09:33:17 INFO - PROCESS | 3681 | Values: 152.0 129.0 127.0 169.0 123.0 123.0 144.0 125.0 154.0 124.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 09:33:17 INFO - PROCESS | 3681 | Values: 160.0 162.0 161.0 161.0 161.0 159.0 159.0 161.0 160.0 160.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:16.63 (22.2%) stddev-sans-first:17.46 09:33:17 INFO - PROCESS | 3681 | Values: 75.0 75.0 75.0 125.0 74.0 74.0 77.0 74.0 96.0 73.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:83.00 stddev:15.39 (18.5%) stddev-sans-first:16.02 09:33:17 INFO - PROCESS | 3681 | Values: 84.0 84.0 114.0 84.0 82.0 82.0 108.0 83.0 83.0 121.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:33:17 INFO - PROCESS | 3681 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 118.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:33:17 INFO - PROCESS | 3681 | Values: 145.0 146.0 146.0 145.0 147.0 146.0 145.0 146.0 145.0 146.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:33:17 INFO - PROCESS | 3681 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.25 (2.5%) stddev-sans-first:1.30 09:33:17 INFO - PROCESS | 3681 | Values: 50.0 49.0 49.0 51.0 50.0 52.0 53.0 51.0 51.0 51.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 09:33:17 INFO - PROCESS | 3681 | Values: 65.0 65.0 65.0 65.0 65.0 64.0 64.0 65.0 65.0 65.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:77.50 stddev:9.56 (12.3%) stddev-sans-first:9.99 09:33:17 INFO - PROCESS | 3681 | Values: 76.0 75.0 75.0 81.0 77.0 77.0 78.0 78.0 107.0 82.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:124.50 stddev:10.23 (8.2%) stddev-sans-first:10.81 09:33:17 INFO - PROCESS | 3681 | Values: 127.0 122.0 115.0 115.0 117.0 115.0 141.0 136.0 137.0 118.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:156.50 stddev:1.33 (0.9%) stddev-sans-first:1.41 09:33:17 INFO - PROCESS | 3681 | Values: 156.0 158.0 156.0 154.0 157.0 157.0 155.0 156.0 154.0 157.0 09:33:17 INFO - PROCESS | 3681 | 09:33:17 INFO - PROCESS | 3681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 09:33:17 INFO - PROCESS | 3681 | Values: 62.0 61.0 62.0 61.0 62.0 61.0 62.0 61.0 60.0 60.0 09:33:17 INFO - PROCESS | 3681 | -------- Summary: end -------- 09:33:17 INFO - PROCESS | 3681 | 09:33:18 INFO - TEST-INFO | 3681: exit 0 09:33:18 INFO - TEST-OK | kraken | took 59971ms 09:33:18 INFO - SUITE-END | took 1174s 09:33:18 INFO - Completed test suite (00:19:35) 09:33:18 INFO - javascript benchmark 09:33:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.7128944039755, 3391.7286617070636, 3400.166959095022, 3414.8292585370727, 3431.2794313194227, 5610.747516361764, 5689.800856970001, 5695.231143795586, 5706.800261990831, 5726.541876649873, 5617.354004289508, 5676.432356764331, 5680.0, 5682.602217844753, 5687.886242275152, 4911.975440122795, 4970.15507363749, 5096.3374761281075, 5098.337216161894, 5129.28190053391, 5038.622103342245, 5075.695458272516, 5080.288759573663, 5085.237214417848, 5087.287779710843, 5021.27191557224, 5070.518300761421, 5107.978404319132, 5123.666961647499, 5139.460356662553, 5197.0125676121415, 5198.740062996872, 5215.739213039352, 5218.999999999981, 5232.215167724834, 4906.31311616374, 5012.924806127911, 5086.237064440326, 5108.591312694975, 5118.6416950813455, 5444.210589464545, 5460.290162278918, 5464.3442786865435, 5473.2063850741815, 5534.501894829485, 5995.0, 6001.639901605918, 6027.638341699512, 6030.306514750816, 6035.456808887221, 5992.0, 6038.516918646497, 6059.0608455689435, 6067.605605635619, 6113.694315284218, 5710.400407957188, 5719.5996280260815, 5734.856628584277, 5743.971280143573, 5757.222774925373, 5948.167256584082, 6252.5935814171935, 6255.311915689271, 6257.311695713468, 6257.7184026718905, 5912.231409916683, 5956.493698035629, 5961.5826892118, 6062.969295219823, 6077.270727512639, 6100.356533716556, 6111.537044596885, 6112.23692137642, 6116.816495505142, 6131.049687298476, 5743.913841292384, 5979.19280897082, 6084.969575152096, 6095.939040609537, 6104.755809767559, 6017.729202185825, 6077.270727512727, 6108.076676490625, 6108.725107370179, 6130.693465326716, 4638.721676699409, 4640.83757068501, 4645.256758918556, 4647.976760116246, 4677.134730074947, 4007.338789099824, 4074.694397920168, 4100.630943215125, 4147.834086636561, 4178.185253875526, 5781.624194427349, 5794.188813566104, 5803.361630220672, 5807.332156801937, 5826.067829147316, 6133.4012270796065, 6134.259836200362, 6134.938650613526, 6142.938570614326, 6148.354422785633, 5527.917081243784, 5701.4013528579735, 5753.798617048467, 5766.0, 5800.100984347433, 5260.763265653055, 5262.105442074857, 5265.868353291198, 5268.130758424817, 5272.499112584298, 6344.460720838688, 6350.682465876687, 6354.586951848208, 6355.936440635627, 6363.522735794834, 5868.589198756217, 6028.306744724413, 6096.0, 6096.177016102769, 6158.137860699416, 6309.716062777278, 6316.0, 6358.586691865199, 6367.968160159169, 6373.426391624776, 5778.133280008033, 6010.489108425658, 6017.398260173947, 6021.397860213943, 6021.638701677914, 5754.884902302014, 6030.668313242813, 6048.274207095177, 6153.907691384722, 6154.815355539341, 5966.820995370146, 6001.669908154919, 6003.249593800775, 6007.459328660441, 6009.699515024144, 5668.2064510967675, 5880.852978675396, 5897.410258974069, 5938.465538101592, 5947.9702601486715, 5858.443447872445, 5960.552958528041, 5963.671998040149, 5973.163757073926, 5988.191594134736, 6040.909386359121, 6060.363661815446, 6060.696965151813, 6074.577489034846, 6088.3911608840535, 5574.358948720786, 5764.798232061858, 5835.766569337349, 5925.40745925404, 5964.463198312173], "value": 5593.588358289817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.5490414545309, 631.3560168628, 655.9672016399179, 656.6815094679076, 670.7685848382309, 34190.658093419086, 46365.536344636545, 47129.293060604075, 47264.29103563441, 47287.527124728746, 48823.51176488234, 49306.26040609385, 49407.0, 49460.0, 49524.50475495244, 8517.914820851805, 9173.63305467782, 9332.113449222314, 10968.122550196005, 11276.887231127706, 10919.235653504258, 11023.83464248037, 11028.28316159451, 11031.117510599173, 11037.558497660084, 11109.0, 11133.498992545356, 11168.66494005181, 11177.0, 11191.888081119207, 8492.915070849274, 11018.0, 11250.268732532359, 11270.042046426064, 11297.0, 10712.303700259536, 10787.406692631903, 10794.838077428845, 11108.111351091893, 11124.721881953015, 46372.0, 47056.76471617654, 47070.76464617689, 47167.0, 47261.29108063382, 3285.4250506161566, 3293.901182964515, 3602.189507360823, 3736.40217565191, 4234.830606775725, 33477.83261083679, 34147.4877876832, 34153.0, 36090.81954590236, 38156.80921595374, 4818.051232942288, 4847.854564363075, 4928.605711543068, 4946.505349465025, 4965.950340496585, 400526.0, 401028.0, 402683.0, 402821.0, 404385.0, 39634.40548391776, 40141.79929100336, 40181.196376072316, 72615.0, 73291.0, 42482.875856207065, 42600.360994585106, 42733.57266427296, 42744.14511709748, 42746.35880461796, 13563.0, 15466.9226653866, 15521.534353969173, 15675.216239187996, 15681.68636627261, 12617.558385456465, 12948.287844168473, 12952.676183095498, 12993.960483161325, 13096.934515327363, 9535.761605959768, 9572.712818615453, 9598.520073996273, 9646.228301735844, 9657.082577155159, 3616.349057169735, 3667.1015601177883, 3703.574088979748, 3727.9440808387894, 3758.3047136279874, 1096.6733910842077, 1102.1403305421784, 1144.3420033480782, 1172.2966220267772, 1270.574357590199, 34461.827690861384, 34841.825790870884, 35080.82459587686, 35131.0, 35191.824040879634, 1163.552032467506, 1182.869884312725, 1193.242291145129, 1263.570386068741, 1283.4737757519372, 17717.379891703727, 18347.449576512725, 18392.816071839377, 18452.26190952347, 18494.907525462557, 600501.0, 606012.0, 608300.0, 608506.0, 608563.0, 64302.67848660727, 64439.67780161069, 64756.35243647597, 65034.67482662557, 65177.674111630084, 1289.7420515896858, 1310.3710219094887, 1373.601655519908, 1401.6776141487514, 1411.9364628591738, 31800.0, 35177.47233791546, 35288.11779705528, 35695.46456803202, 35740.82129589335, 62981.37018629755, 63880.36119638744, 64024.35975640184, 64312.35687643064, 64389.35610644021, 559517.0, 560725.0, 565298.0, 565536.0, 565744.0, 49310.50689493203, 50512.24231636602, 51233.23150152825, 51445.22832157595, 51925.18261860817, 55198.0, 55859.44140558542, 55968.0, 56276.71861640666, 56525.15212271737, 45099.774501127285, 45339.0, 47201.29198061963, 47286.76356618195, 47623.28565071458, 44907.77546112249, 45760.54239457563, 45820.54179458163, 46449.53550464452, 46774.766126169154, 45199.77400112979, 46454.0, 46832.0, 46939.530604693515, 47138.76430617825, 39332.606673932896, 40831.38752918768, 41192.79403602963, 41303.7934810324, 41335.0, 30661.23346916345, 30845.845770771004, 30893.38213235678, 31105.222369440944, 31338.0], "value": 21412.74771064092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.4588818089207, 433.7635988386329, 433.8850204695754, 436.83181974939646, 438.9912201755965, 19102.713459298106, 20339.694904576405, 20877.686834697473, 21646.24238151665, 21648.567028659418, 19809.900950495226, 20285.594288114266, 20508.69236961447, 20531.692024619606, 20575.485612859688, 5587.525060369863, 5775.826725198251, 5797.768089276445, 5807.970960145193, 5818.767249310023, 5677.829665110033, 5729.914051289234, 5792.131180322965, 5794.130880367937, 5795.130730390433, 5726.083826587746, 5729.828105156852, 5729.856753581173, 5735.827925162252, 5747.195392645033, 5645.887082258352, 5773.682447465388, 5776.682282474462, 5779.017567013618, 5779.162021506879, 4985.351904252424, 5076.771545280434, 5080.771365288534, 5088.287639730441, 5103.336566246401, 15182.392704291815, 15676.451324203597, 22504.662430063563, 23291.650625240636, 23298.65052024221, 2228.74369447514, 2262.3778460923213, 2331.9067237310487, 2485.3538080099306, 3000.7149320814488, 11147.832782508187, 12146.514139434412, 13685.315734213249, 15088.849111508964, 15286.159261240628, 1988.532694816717, 2010.6581881080251, 2065.731454910867, 2088.937331880046, 2089.2896415218747, 80674.59662701708, 82196.58901705453, 84483.0, 85486.0, 86078.99999999937, 11585.884141158649, 11638.974950642347, 11742.412879356169, 11855.347955862338, 11901.583444579399, 16453.83546164547, 16618.169091545373, 16623.6675266494, 16631.41790037367, 16650.167491625612, 8229.506229626122, 8299.128591497927, 8311.750647480585, 8366.623501942306, 8406.495610263404, 7790.883136752953, 7855.096663883613, 7920.960395198103, 7992.400569957276, 8196.83606327882, 5027.497250274943, 5039.470855560114, 5103.0, 5114.616403769658, 5169.81905633301, 2089.3000844716885, 2117.4918019675165, 2139.539998900244, 2147.2484630379554, 2164.4372463159375, 883.5935469683889, 885.2696525366598, 952.3761935931975, 991.965281215154, 1058.4654749351528, 18667.15997780076, 18976.620467590572, 19260.422187334403, 19496.610067798563, 19764.70352944707, 1024.6721049264163, 1026.5329275179774, 1067.530286673861, 1082.4263140535372, 1113.5267511307661, 10623.309484883457, 10755.731106722238, 10851.0, 10857.619983300547, 10931.836022459669, 393373.0, 394513.0, 396414.0, 487336.0, 491699.0, 95293.0, 101440.0, 101530.0, 101700.49149754204, 101910.0, 1110.1174566219875, 1117.407773879845, 1132.7847708935276, 1134.9546018159347, 1145.495981768036, 17488.650226995644, 17720.911395443454, 17748.29006839712, 17769.289228430716, 17845.196966136296, 29788.32661001665, 40526.1894762109, 42611.7869410651, 43411.34882976821, 43490.782546087066, 58540.414595853494, 59477.40522594892, 59512.70243648927, 60137.39862601318, 60177.0, 7706.190849960673, 8194.549299788569, 8269.669213231404, 8388.257037956068, 9075.410098343587, 7057.258987806412, 9323.860142097737, 12274.202176858476, 13197.142185757895, 13728.244946528037, 11421.828672570084, 11463.656090317303, 11475.885241147816, 11556.711082223012, 11854.762904742029, 10526.842097368699, 10957.890421096006, 11240.494177761857, 11260.324380537195, 11602.70993225176, 10689.25175237757, 11189.440527973406, 11209.159313051718, 11462.598809041643, 11610.0, 8407.318536292643, 8413.789655258668, 8422.24199822019, 8551.358648101294, 8654.177853103945, 11423.657290281304, 11491.655250342503, 11534.82697759551, 11552.306861588331, 11842.703932401759], "value": 9361.04466646526, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.7469668111394, 1562.679650671612, 1581.2568092996291, 1776.9733453998185, 1922.6154769046202, 9345.252379809615, 9672.66145684902, 9707.660231891876, 9947.104760571565, 9981.251406144533, 9727.0, 9892.109710126088, 9894.50527473627, 9909.554070066846, 9932.106110450059, 2159.0608085482786, 2211.2592281585607, 2484.664570283007, 2527.128140791433, 2554.118829003991, 2520.1935380678096, 2538.340031591781, 2540.3649087728068, 2540.8475491470476, 2545.6690630218045, 2457.3979375053136, 2538.0355464923305, 2538.3019669590917, 2538.6671997201433, 2539.4032402385424, 2139.946501337471, 2392.7248366437907, 2406.879656017201, 2464.9753502464923, 2467.864267465289, 2459.754024597526, 2469.6295555666616, 2474.072222916406, 2491.5888878335054, 2493.0775613023116, 9979.750506237402, 10055.446950417723, 10058.04448577384, 10058.396496210251, 10060.346077504939, 5857.4435428634215, 6000.699965001776, 6001.609895356788, 6003.879922401527, 6010.57925945184, 3617.981910090459, 3733.775973441575, 3753.7372383933134, 3807.8857634271017, 3868.613138686137, 1157.2940506291156, 1167.3987896233446, 1200.9399530023463, 1226.760781647579, 1231.7536492701495, 14403.495877644336, 14444.0, 14478.56564303062, 14491.492797752133, 14510.419583216659, 5198.32421785173, 5209.010288045335, 5257.290265814143, 5303.549198318109, 5321.068812957717, 11765.470553825098, 11826.172167948162, 11841.0, 11858.94070529659, 11901.76196476083, 5870.501007414332, 5922.644641321448, 5928.792492262836, 5929.0, 5930.703464826742, 4731.077439899255, 5068.898622027539, 5231.581473482112, 5233.607479439057, 5336.759845806948, 4503.2569626012, 4522.638188944877, 4575.656825738017, 4598.724076555417, 4609.446866376056, 1388.0699931046224, 1417.340936464549, 1432.6919712261913, 1438.0724432740824, 1442.942282308696, 1048.2400259811727, 1070.7858428314369, 1071.2501249125644, 1072.8176210044155, 1088.553692985872, 6549.377809108127, 6730.899036514456, 6735.42748866342, 6788.694508747119, 6823.078884350549, 467.9836205732783, 476.39973633221695, 487.939007624047, 492.4607554727568, 492.74623568862063, 4560.840370587013, 4642.280446530793, 4661.277501987197, 4725.243960966229, 4756.9524304756505, 12615.558455454015, 12882.80675789864, 12955.611331660066, 12958.0, 12976.610701678965, 12188.939055304667, 12572.182808117443, 12584.81122783159, 12978.870211297955, 12993.870061299454, 9197.908020919838, 9412.105849944244, 9412.105849944244, 9419.71740847762, 9420.293477989178, 6603.636799975855, 6859.965700171667, 6931.376176144171, 7007.089078419773, 7021.227664956748, 11612.477438515118, 11614.0, 11714.531418743154, 11957.701057473632, 12034.458449369975, 12038.458269377725, 12080.818787718366, 12150.513979440724, 12250.264984100626, 12298.938505307417, 8039.718609848627, 8638.956805215934, 8834.514101724468, 8946.597403116744, 9104.635814567608, 9991.550380233053, 10000.0, 10029.648962286285, 10095.7476063099, 10098.141657959155, 8530.530820804915, 8724.996625388161, 8829.779255518664, 8893.28853691716, 8938.061503542034, 8264.090949995616, 8320.70877519284, 8428.241458268785, 8448.028476725256, 8548.700795472128, 7631.847363052597, 7698.576578288248, 7765.961170194113, 7780.0, 7787.143414224318, 5535.169724541351, 5629.549636029026, 5778.797742079007, 5869.706514674334, 5894.0, 5692.1461780733225, 5697.4587414195585, 5722.284714410699, 5736.913946290892, 5812.476877081083], "value": 5088.67974689617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.445970906259, 358.2119337457594, 362.35139101009196, 364.34599893191717, 367.4011361480786, 5542.612017158801, 5546.0, 5578.302712160975, 5585.8603534911645, 5602.8319150425505, 5256.448072952342, 5376.731163441822, 5376.865578360543, 5387.299651045369, 5514.476124768151, 4180.749155050691, 4247.088810035489, 4263.593014305287, 4290.206311832321, 4320.351947207928, 4259.5740425957465, 4284.207421626993, 4288.549702281247, 4293.65650747941, 4317.503487098992, 4083.9591604083876, 4240.180280113721, 4244.0, 4258.91482170358, 4319.503257125423, 4233.047564298039, 4239.5548467410945, 4244.469441319835, 4256.616904478597, 4278.55075217104, 3763.8118094095184, 3766.1902690921584, 3776.4146557283666, 3781.1870447853566, 3785.9053523661855, 5210.505002024802, 5252.030488566787, 5295.391030031557, 5310.7610157543, 5351.197320401932, 4854.732989685566, 4863.076015557032, 4868.0, 4882.170031094723, 4892.828750993698, 5714.428557144252, 5725.826205627836, 5786.971065144648, 5791.420857914175, 5795.1017592273265, 3957.129431525089, 4122.484689413824, 4144.79276036197, 4153.771542565158, 4157.750534967911, 5842.824715258549, 5900.498457631106, 5935.169076329317, 5958.821235362946, 5964.0, 4244.448221731217, 4251.213525497793, 4262.296721041055, 4263.381809637585, 4290.377895205178, 4139.523954745183, 4264.466941632296, 4266.31738921771, 4278.251306021434, 4320.157569273962, 4787.760611969387, 4821.783019764119, 4845.685030473008, 4847.709137451764, 4851.126797176472, 5724.656520608777, 5735.741891614887, 5744.109663002241, 5804.2744656917885, 5811.3316968548315, 1427.3719563392074, 1469.4563011685746, 1474.4544518528214, 1481.8811797093342, 1495.3121564080427, 942.9156470059487, 953.3993584042009, 963.889152747429, 969.5588507229194, 971.4317124482257, 5059.696418214845, 5064.18972964324, 5075.467075957045, 5076.492350764968, 5082.822101226513, 5270.314859068373, 5297.629165958423, 5318.893622127536, 5327.254184414185, 5329.600279979017, 4669.276242211205, 4955.603551715854, 5060.886604946906, 5086.3133476980875, 5090.26191202281, 2794.413173233603, 2808.606795048695, 2827.448647513715, 2833.9656025550526, 2857.5570786527705, 5374.247605335257, 5378.946210537844, 5421.945780542223, 5441.5851878511085, 5444.891102177934, 5285.097364948615, 5320.813771518056, 5604.747786349624, 6073.1497590337385, 6074.088886666947, 6032.366601506867, 6071.907056729743, 6074.787382441682, 6172.629642221481, 6175.536834737412, 5655.660660360391, 5894.0, 5894.498967587803, 5907.995640740998, 5953.345132035559, 5812.447817457335, 5830.737616807339, 5846.269216347957, 5851.561132914963, 5859.267591551056, 6159.844054580861, 6160.014397696347, 6161.383861613803, 6186.845328866814, 6190.823743488722, 4034.1931613676793, 4155.688323375698, 4167.229062623424, 4254.574542545721, 4287.121140166196, 5855.765769369177, 5878.382769809279, 5890.705464726832, 5904.734286957011, 5919.437653422918, 5769.192313076089, 5769.644133628678, 5770.596058275881, 5783.595148339744, 5802.94197058024, 5615.427680249537, 5686.6400443815355, 5702.543796496353, 5720.8283751487525, 5753.884922301447, 5636.267285252891, 5638.689872057076, 5643.435656434488, 5655.717214139194, 5658.886822263614, 5583.637063590853, 5737.311522617312, 5780.826575202751, 5782.739776709974, 5783.710814459176, 5603.982566276219, 5636.633618814764, 5644.887102258014, 5647.181158731879, 5672.205891175321], "value": 4321.672658880604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.9480033797803, 808.6846130009294, 811.1766556944702, 825.3768404854334, 893.5621545442727, 15665.451709190167, 17459.0, 18190.27238910445, 18227.088645567736, 18244.361447349347, 15817.762733558977, 16359.345626174938, 16512.422065227718, 16520.0, 17032.82967170328, 892.8392889279927, 893.6604090445621, 898.8561830107184, 899.8515244984602, 900.2977677411629, 853.4623187391934, 854.4360721923532, 855.6064210463195, 855.7389996051202, 867.839449701804, 945.7730144765276, 953.2469349214113, 953.4946478366477, 954.308126608207, 955.1642312976146, 948.8244674735162, 950.1353768071023, 950.6435086842434, 950.828850806858, 952.2286947572488, 699.5033526196406, 699.5313140196081, 700.0444393403019, 700.1563116444723, 700.5586480517293, 16475.17624118789, 16491.0, 16552.089635070068, 16554.503364899072, 16597.41909033178, 15403.306851191723, 15514.5345639631, 15648.758424709511, 15886.841131588651, 16349.346026158939, 12461.75076498474, 12945.288009159493, 12979.41592628334, 13131.0808243423, 13256.933715331457, 3993.3011722948368, 4078.9592104079175, 4097.836086556535, 4113.218488487208, 4161.459010328638, 67922.32077679259, 68419.65790171018, 68496.0, 68711.31288687099, 78154.21845781583, 12026.879731202751, 12350.567730129402, 12373.75252494945, 12441.066919981038, 12594.433250503565, 21515.67726484104, 22415.0, 22708.545829083327, 22766.31701048971, 22800.657990130163, 9147.342346375326, 9347.579358928728, 9357.859632105525, 9383.10860468268, 9569.28230382724, 4953.2074868020945, 4999.625028122905, 5031.069252188357, 5121.411037730709, 5124.256982737556, 4009.69927255457, 4183.518895327015, 4189.120284740177, 4205.6635469163, 4210.494740631144, 728.0498948871773, 741.0514541387033, 745.3999530377989, 753.3182469864781, 753.6570860258569, 695.4193248637343, 740.6481921087441, 763.3892885691424, 774.1136398823315, 813.5647428625775, 10603.522841472151, 10801.70794533381, 10946.343219406861, 11020.614278500214, 11066.944665276622, 671.6473851228144, 733.9211359301818, 754.4379437319233, 813.9511629302261, 835.4820011592851, 5033.949660503421, 5123.7438128093445, 5132.743362831843, 5136.075506408883, 5143.305653736773, 101596.49201754092, 112459.0, 113558.43220783843, 120566.0, 120732.0, 98787.0, 99775.50112249538, 101276.49361753292, 102681.48659256805, 102817.48591257146, 51322.74338628283, 76574.61712691512, 87497.56251218703, 90326.54836725906, 94004.52997735105, 10175.338602990782, 10217.897821021992, 10266.435346056038, 10284.897151028694, 10315.535800888827, 53856.73071634617, 55641.0, 55810.44189558052, 56781.71609141928, 57368.13947790703, 69421.30578694148, 70278.0, 70342.64828675824, 77021.61489192519, 77065.0, 13648.590542283748, 13922.0, 13933.23367214813, 13934.233617151154, 14066.156030638194, 15153.379712791331, 16014.0, 16120.758188627415, 16178.352865885232, 16267.186640668157, 14214.373381963655, 14452.494162704255, 14588.489402870848, 14655.120692758468, 14710.852891470948, 14041.859581404464, 14249.515280443082, 14903.925480372962, 15109.773353399489, 15410.537683869501, 14886.851131488547, 15088.622784430478, 15331.923340383226, 15791.21043947775, 15804.841951580796, 12299.385030748248, 12546.811797822857, 12560.560380386643, 12829.486820527447, 12960.935195323964, 11962.222455540363, 12235.434896417479, 12309.384530773605, 12529.561465348668, 12656.43046062911], "value": 8155.719791888381, "unit": "score"}], "name": "dromaeo_css", "value": 7654.348733599853, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 111.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 129.0, 127.0, 169.0, 123.0, 123.0, 144.0, 125.0, 154.0, 124.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 161.0, 161.0, 159.0, 159.0, 161.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 125.0, 74.0, 74.0, 77.0, 74.0, 96.0, 73.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 114.0, 84.0, 82.0, 82.0, 108.0, 83.0, 83.0, 121.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 51.0, 50.0, 52.0, 53.0, 51.0, 51.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 81.0, 77.0, 77.0, 78.0, 78.0, 107.0, 82.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 115.0, 115.0, 117.0, 115.0, 141.0, 136.0, 137.0, 118.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 156.0, 154.0, 157.0, 157.0, 155.0, 156.0, 154.0, 157.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 61.0, 62.0, 61.0, 62.0, 61.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1475.6000000000001, "alertThreshold": 2.0}]} 09:33:18 INFO - Return code: 0 09:33:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:18 ERROR - # TBPL SUCCESS # 09:33:18 INFO - Running post-action listener: _resource_record_post_action 09:33:18 INFO - ##### 09:33:18 INFO - ##### Finished run-tests step (success) 09:33:18 INFO - ##### 09:33:18 INFO - Running post-run listener: _resource_record_post_run 09:33:18 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 7970816; Write bytes: 391933952; Read time: 1244; Write time: 373104 09:33:18 INFO - TinderboxPrint: CPU usage
13.1% 09:33:18 INFO - TinderboxPrint: I/O read bytes / time
7,970,816 / 1,244 09:33:18 INFO - TinderboxPrint: I/O write bytes / time
391,933,952 / 373,104 09:33:18 INFO - TinderboxPrint: CPU idle
8,268.6 (86.9%) 09:33:18 INFO - TinderboxPrint: CPU user
1,198.0 (12.6%) 09:33:18 INFO - TinderboxPrint: Swap in / out
0 / 0 09:33:18 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 15589376; Read time: 0; Write time: 4612 09:33:18 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 6209536; Write bytes: 376344576; Read time: 1204; Write time: 368492 09:33:19 INFO - Running post-run listener: _upload_blobber_files 09:33:19 INFO - Blob upload gear active. 09:33:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:19 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 09:33:19 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'] 09:33:19 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 09:33:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:23 INFO - (blobuploader) - INFO - Done attempting. 09:33:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:33:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:33:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:24 INFO - (blobuploader) - INFO - Done attempting. 09:33:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:33:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:25 INFO - (blobuploader) - INFO - Done attempting. 09:33:25 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:25 INFO - Return code: 0 09:33:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e7bbc75c1a7adcd5951c4ead2c31db42f66bf782b507fcf96adcd53ea6736d2c8794b5d3807f0989bdf77ec6c1a3fe924db2a521a1d4413df3b13072d66c801", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cf9a1b4c1e72920d73ea6a95fda1a304897ac796fade92249b6c8917304a0eda9e6addc97a3ba08525427b732a856b8e5c9c33a1cf6af0825b4b2c5929633de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88d5445a92215a0bb78ee32b78f8fb7557ceb7d6d51e8168b2ea4c777ffbb1e13b0103360e4d1314a402067ed35fb72e0ba1a19769be7bcfa8e5d64a50b9bbe0"} 09:33:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:25 INFO - Contents: 09:33:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e7bbc75c1a7adcd5951c4ead2c31db42f66bf782b507fcf96adcd53ea6736d2c8794b5d3807f0989bdf77ec6c1a3fe924db2a521a1d4413df3b13072d66c801", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cf9a1b4c1e72920d73ea6a95fda1a304897ac796fade92249b6c8917304a0eda9e6addc97a3ba08525427b732a856b8e5c9c33a1cf6af0825b4b2c5929633de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88d5445a92215a0bb78ee32b78f8fb7557ceb7d6d51e8168b2ea4c777ffbb1e13b0103360e4d1314a402067ed35fb72e0ba1a19769be7bcfa8e5d64a50b9bbe0"} 09:33:25 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:25 INFO - Copying logs to upload dir... 09:33:25 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.667009 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-23 09:33:25.197401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 09:33:25.200464) ========= 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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 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/0e7bbc75c1a7adcd5951c4ead2c31db42f66bf782b507fcf96adcd53ea6736d2c8794b5d3807f0989bdf77ec6c1a3fe924db2a521a1d4413df3b13072d66c801", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cf9a1b4c1e72920d73ea6a95fda1a304897ac796fade92249b6c8917304a0eda9e6addc97a3ba08525427b732a856b8e5c9c33a1cf6af0825b4b2c5929633de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88d5445a92215a0bb78ee32b78f8fb7557ceb7d6d51e8168b2ea4c777ffbb1e13b0103360e4d1314a402067ed35fb72e0ba1a19769be7bcfa8e5d64a50b9bbe0"} build_url:https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/aartmEjXR3KKW6ShCTgBKg/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/0e7bbc75c1a7adcd5951c4ead2c31db42f66bf782b507fcf96adcd53ea6736d2c8794b5d3807f0989bdf77ec6c1a3fe924db2a521a1d4413df3b13072d66c801", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cf9a1b4c1e72920d73ea6a95fda1a304897ac796fade92249b6c8917304a0eda9e6addc97a3ba08525427b732a856b8e5c9c33a1cf6af0825b4b2c5929633de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88d5445a92215a0bb78ee32b78f8fb7557ceb7d6d51e8168b2ea4c777ffbb1e13b0103360e4d1314a402067ed35fb72e0ba1a19769be7bcfa8e5d64a50b9bbe0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 09:33:25.227183) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:33:25.227882) ========= 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=03627c79f6bb3d1578c4a402000001ba-1466698275.778230-782937986 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004369 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:33:25.309465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 09:33:25.309820) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 09:33:25.310205) ========= ========= Total master_lag: 0.19 =========