builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1469727991.99 results: success (0) buildid: 20160728070523 builduid: 62b5ec94122c4d369086bc4b6fc7af93 revision: db8d264de7e3ed62616a1b36bf7a037273faea58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:31.989015) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:31.989693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:31.990074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:32.008113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:32.008503) ========= 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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-28 10:46:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-07-28 10:46:32 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124388 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:32.147160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:32.147505) ========= 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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016079 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:32.197260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 10:46:32.197650) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev db8d264de7e3ed62616a1b36bf7a037273faea58 --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 db8d264de7e3ed62616a1b36bf7a037273faea58 --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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-28 10:46:32,565 truncating revision to first 12 chars 2016-07-28 10:46:32,565 Setting DEBUG logging. 2016-07-28 10:46:32,565 attempt 1/10 2016-07-28 10:46:32,565 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/db8d264de7e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-28 10:46:33,722 unpacking tar archive at: mozilla-aurora-db8d264de7e3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.464570 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 10:46:33.675978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:33.676284) ========= script_repo_revision: db8d264de7e3ed62616a1b36bf7a037273faea58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:33.676679) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:33.676956) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 10:46:33.686953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-28 10:46:33.687360) ========= /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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:46:34 INFO - MultiFileLogger online at 20160728 10:46:34 in /builds/slave/test 10:46:34 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 10:46:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:46:34 INFO - {'append_to_log': False, 10:46:34 INFO - 'base_work_dir': '/builds/slave/test', 10:46:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:46:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:46:34 INFO - 'branch': 'Mozilla-Aurora', 10:46:34 INFO - 'buildbot_json_path': 'buildprops.json', 10:46:34 INFO - 'config_files': ('talos/linux_config.py',), 10:46:34 INFO - 'default_actions': ('clobber', 10:46:34 INFO - 'read-buildbot-config', 10:46:34 INFO - 'download-and-extract', 10:46:34 INFO - 'populate-webroot', 10:46:34 INFO - 'create-virtualenv', 10:46:34 INFO - 'install', 10:46:34 INFO - 'run-tests'), 10:46:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:46:34 INFO - 'download_minidump_stackwalk': True, 10:46:34 INFO - 'download_symbols': 'ondemand', 10:46:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:46:34 INFO - 'tooltool.py': '/tools/tooltool.py', 10:46:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:46:34 INFO - '/tools/misc-python/virtualenv.py')}, 10:46:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:46:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:46:34 INFO - 'installer_path': 'installer.exe', 10:46:34 INFO - 'log_level': 'info', 10:46:34 INFO - 'log_name': 'talos', 10:46:34 INFO - 'log_to_console': True, 10:46:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:46:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:46:34 INFO - 'opt_config_files': (), 10:46:34 INFO - 'pip_index': False, 10:46:34 INFO - 'sps_profile': False, 10:46:34 INFO - 'sps_profile_interval': 0, 10:46:34 INFO - 'suite': 'dromaeojs', 10:46:34 INFO - 'system_bits': '32', 10:46:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:46:34 INFO - 'title': 'talos-linux64-ix-003', 10:46:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:46:34 INFO - 'use_talos_json': True, 10:46:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:46:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:46:34 INFO - 'work_dir': 'build'} 10:46:34 INFO - ##### 10:46:34 INFO - ##### Running clobber step. 10:46:34 INFO - ##### 10:46:34 INFO - Running pre-action listener: _resource_record_pre_action 10:46:34 INFO - Running main action method: clobber 10:46:34 INFO - rmtree: /builds/slave/test/build 10:46:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:46:34 INFO - Running post-action listener: _resource_record_post_action 10:46:34 INFO - ##### 10:46:34 INFO - ##### Finished clobber step (success) 10:46:34 INFO - ##### 10:46:34 INFO - ##### 10:46:34 INFO - ##### Running read-buildbot-config step. 10:46:34 INFO - ##### 10:46:34 INFO - Running pre-action listener: _resource_record_pre_action 10:46:34 INFO - Running main action method: read_buildbot_config 10:46:34 INFO - Using buildbot properties: 10:46:34 INFO - { 10:46:34 INFO - "project": "", 10:46:34 INFO - "product": "firefox", 10:46:34 INFO - "script_repo_revision": "production", 10:46:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 10:46:34 INFO - "repository": "", 10:46:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 10:46:34 INFO - "buildid": "20160728070523", 10:46:34 INFO - "pgo_build": "True", 10:46:34 INFO - "basedir": "/builds/slave/test", 10:46:34 INFO - "buildnumber": 642, 10:46:34 INFO - "slavename": "talos-linux64-ix-003", 10:46:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:46:34 INFO - "platform": "ubuntu64_hw", 10:46:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 10:46:34 INFO - "branch": "mozilla-aurora", 10:46:34 INFO - "repo_path": "releases/mozilla-aurora", 10:46:34 INFO - "revision": "db8d264de7e3ed62616a1b36bf7a037273faea58", 10:46:34 INFO - "stage_platform": "linux64-pgo", 10:46:34 INFO - "builduid": "62b5ec94122c4d369086bc4b6fc7af93", 10:46:34 INFO - "slavebuilddir": "test" 10:46:34 INFO - } 10:46:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 10:46:34 INFO - Running post-action listener: _resource_record_post_action 10:46:34 INFO - ##### 10:46:34 INFO - ##### Finished read-buildbot-config step (success) 10:46:34 INFO - ##### 10:46:34 INFO - ##### 10:46:34 INFO - ##### Running download-and-extract step. 10:46:34 INFO - ##### 10:46:34 INFO - Running pre-action listener: _resource_record_pre_action 10:46:34 INFO - Running main action method: download_and_extract 10:46:34 INFO - mkdir: /builds/slave/test/build/tests 10:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:34 INFO - https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:46:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 10:46:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 10:46:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:35 INFO - Downloaded 1361 bytes. 10:46:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 10:46:35 INFO - Using the following test package requirements: 10:46:35 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 10:46:35 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 10:46:35 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 10:46:35 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'jsshell-linux-x86_64.zip'], 10:46:35 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 10:46:35 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 10:46:35 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 10:46:35 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 10:46:35 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 10:46:35 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 10:46:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 10:46:35 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:35 INFO - https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:46:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 10:46:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 10:46:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:36 INFO - Downloaded 24494012 bytes. 10:46:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:46:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:46:37 INFO - Return code: 0 10:46:37 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 10:46:37 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')]} 10:46:37 INFO - https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:46:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 10:46:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 10:46:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:38 INFO - Downloaded 24494012 bytes. 10:46:38 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'] 10:46:38 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 10:46:40 INFO - Return code: 0 10:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:40 INFO - https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:46:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 10:46:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 10:46:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:41 INFO - Downloaded 10833369 bytes. 10:46:41 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'] 10:46:41 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 10:46:41 INFO - Return code: 0 10:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:41 INFO - https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:46:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 10:46:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 10:46:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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 10:46:43 INFO - Downloaded 63650015 bytes. 10:46:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 10:46:43 INFO - mkdir: /builds/slave/test/properties 10:46:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:46:43 INFO - Writing to file /builds/slave/test/properties/build_url 10:46:43 INFO - Contents: 10:46:43 INFO - build_url:https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 10:46:43 INFO - Running post-action listener: _resource_record_post_action 10:46:43 INFO - Running post-action listener: set_extra_try_arguments 10:46:43 INFO - ##### 10:46:43 INFO - ##### Finished download-and-extract step (success) 10:46:43 INFO - ##### 10:46:43 INFO - ##### 10:46:43 INFO - ##### Running populate-webroot step. 10:46:43 INFO - ##### 10:46:43 INFO - Running pre-action listener: _resource_record_pre_action 10:46:43 INFO - Running main action method: populate_webroot 10:46:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:46:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:46:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:46:43 INFO - '--tppagecycles', 10:46:43 INFO - '7'], 10:46:43 INFO - 'tests': ['tsvgm']}}, 10:46:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:46:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:46:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:46:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:46:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:46:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:46:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['damp', 'tps']}, 10:46:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'tests': ['damp', 'tps']}, 10:46:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['dromaeo_dom']}, 10:46:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:46:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'other-e10s': {'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['a11yr', 10:46:43 INFO - 'ts_paint', 10:46:43 INFO - 'tpaint', 10:46:43 INFO - 'sessionrestore', 10:46:43 INFO - 'sessionrestore_no_auto_restore', 10:46:43 INFO - 'tabpaint']}, 10:46:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['tsvgx', 10:46:43 INFO - 'tsvgr_opacity', 10:46:43 INFO - 'tart', 10:46:43 INFO - 'tscrollx', 10:46:43 INFO - 'cart']}, 10:46:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:46:43 INFO - 'tsvgr_opacity', 10:46:43 INFO - 'tart', 10:46:43 INFO - 'tscrollx', 10:46:43 INFO - 'cart']}, 10:46:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'talos_options': ['--disable-e10s'], 10:46:43 INFO - 'tests': ['tp5o']}, 10:46:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'tests': ['tp5o']}, 10:46:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'talos_options': ['--disable-e10s', 10:46:43 INFO - '--xperf_path', 10:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:46:43 INFO - 'tests': ['tp5n']}, 10:46:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:46:43 INFO - 'talos_options': ['--xperf_path', 10:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:46:43 INFO - 'tests': ['tp5n']}}, 10:46:43 INFO - 'talos.zip': {'path': '', 10:46:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:46:43 INFO - Running post-action listener: _resource_record_post_action 10:46:43 INFO - ##### 10:46:43 INFO - ##### Finished populate-webroot step (success) 10:46:43 INFO - ##### 10:46:43 INFO - ##### 10:46:43 INFO - ##### Running create-virtualenv step. 10:46:43 INFO - ##### 10:46:43 INFO - Running pre-action listener: _resource_record_pre_action 10:46:43 INFO - Running main action method: create_virtualenv 10:46:43 INFO - Creating virtualenv /builds/slave/test/build/venv 10:46:43 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 10:46:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:46:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:46:43 INFO - Using real prefix '/usr' 10:46:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:46:44 INFO - Installing distribute.............................................................................................................................................................................................done. 10:46:46 INFO - Installing pip.................done. 10:46:46 INFO - Return code: 0 10:46:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:46:46 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')]} 10:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:46 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')]} 10:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:46 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:46 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 10:46:46 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 10:46:46 INFO - Using env: {'DISPLAY': ':0', 10:46:46 INFO - 'HOME': '/home/cltbld', 10:46:46 INFO - 'LANG': 'en_US.UTF-8', 10:46:46 INFO - 'LANGUAGE': 'en_US:en', 10:46:46 INFO - 'LOGNAME': 'cltbld', 10:46:46 INFO - 'MAIL': '/var/mail/cltbld', 10:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:46 INFO - 'MOZ_NO_REMOTE': '1', 10:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:46 INFO - 'NO_EM_RESTART': '1', 10:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:46 INFO - 'PWD': '/builds/slave/test', 10:46:46 INFO - 'SHELL': '/bin/bash', 10:46:46 INFO - 'SHLVL': '1', 10:46:46 INFO - 'TERM': 'linux', 10:46:46 INFO - 'TMOUT': '86400', 10:46:46 INFO - 'USER': 'cltbld', 10:46:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:46 INFO - '_': '/tools/buildbot/bin/python'} 10:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:46 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 10:46:46 INFO - Cleaning up... 10:46:46 INFO - Return code: 0 10:46:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:46:46 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')]} 10:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:46 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')]} 10:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:46 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:46 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 10:46:46 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 10:46:46 INFO - Using env: {'DISPLAY': ':0', 10:46:46 INFO - 'HOME': '/home/cltbld', 10:46:46 INFO - 'LANG': 'en_US.UTF-8', 10:46:46 INFO - 'LANGUAGE': 'en_US:en', 10:46:46 INFO - 'LOGNAME': 'cltbld', 10:46:46 INFO - 'MAIL': '/var/mail/cltbld', 10:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:46 INFO - 'MOZ_NO_REMOTE': '1', 10:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:46 INFO - 'NO_EM_RESTART': '1', 10:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:46 INFO - 'PWD': '/builds/slave/test', 10:46:46 INFO - 'SHELL': '/bin/bash', 10:46:46 INFO - 'SHLVL': '1', 10:46:46 INFO - 'TERM': 'linux', 10:46:46 INFO - 'TMOUT': '86400', 10:46:46 INFO - 'USER': 'cltbld', 10:46:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:46 INFO - '_': '/tools/buildbot/bin/python'} 10:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:46 INFO - Downloading/unpacking psutil>=3.1.1 10:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:46:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:46:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:46:48 INFO - Installing collected packages: psutil 10:46:48 INFO - Running setup.py install for psutil 10:46:48 INFO - building 'psutil._psutil_linux' extension 10:46:48 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 10:46:49 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 10:46:49 INFO - building 'psutil._psutil_posix' extension 10:46:49 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 10:46:49 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 10:46:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:46:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:46:49 INFO - Successfully installed psutil 10:46:49 INFO - Cleaning up... 10:46:49 INFO - Return code: 0 10:46:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:46:49 INFO - Using env: {'DISPLAY': ':0', 10:46:49 INFO - 'HOME': '/home/cltbld', 10:46:49 INFO - 'LANG': 'en_US.UTF-8', 10:46:49 INFO - 'LANGUAGE': 'en_US:en', 10:46:49 INFO - 'LOGNAME': 'cltbld', 10:46:49 INFO - 'MAIL': '/var/mail/cltbld', 10:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:49 INFO - 'MOZ_NO_REMOTE': '1', 10:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:49 INFO - 'NO_EM_RESTART': '1', 10:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:49 INFO - 'PWD': '/builds/slave/test', 10:46:49 INFO - 'SHELL': '/bin/bash', 10:46:49 INFO - 'SHLVL': '1', 10:46:49 INFO - 'TERM': 'linux', 10:46:49 INFO - 'TMOUT': '86400', 10:46:49 INFO - 'USER': 'cltbld', 10:46:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:49 INFO - '_': '/tools/buildbot/bin/python'} 10:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:46:51 INFO - Installing collected packages: mozsystemmonitor 10:46:51 INFO - Running setup.py install for mozsystemmonitor 10:46:51 INFO - Successfully installed mozsystemmonitor 10:46:51 INFO - Cleaning up... 10:46:51 INFO - Return code: 0 10:46:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:46:51 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')]} 10:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:51 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')]} 10:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:51 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:51 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 10:46:51 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 10:46:51 INFO - Using env: {'DISPLAY': ':0', 10:46:51 INFO - 'HOME': '/home/cltbld', 10:46:51 INFO - 'LANG': 'en_US.UTF-8', 10:46:51 INFO - 'LANGUAGE': 'en_US:en', 10:46:51 INFO - 'LOGNAME': 'cltbld', 10:46:51 INFO - 'MAIL': '/var/mail/cltbld', 10:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:51 INFO - 'MOZ_NO_REMOTE': '1', 10:46:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:51 INFO - 'NO_EM_RESTART': '1', 10:46:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:51 INFO - 'PWD': '/builds/slave/test', 10:46:51 INFO - 'SHELL': '/bin/bash', 10:46:51 INFO - 'SHLVL': '1', 10:46:51 INFO - 'TERM': 'linux', 10:46:51 INFO - 'TMOUT': '86400', 10:46:51 INFO - 'USER': 'cltbld', 10:46:51 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:51 INFO - '_': '/tools/buildbot/bin/python'} 10:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:52 INFO - Downloading/unpacking jsonschema==2.5.1 10:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:46:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:46:53 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 10:46:53 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 10:46:53 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 10:46:53 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 10:46:53 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 10:46:53 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 10:46:54 INFO - Downloading functools32-3.2.3-2.tar.gz 10:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:46:54 INFO - warning: no files found matching '*.txt' 10:46:54 INFO - no previously-included directories found matching 'build' 10:46:54 INFO - no previously-included directories found matching 'dist' 10:46:54 INFO - no previously-included directories found matching '.git*' 10:46:54 INFO - Installing collected packages: jsonschema, functools32 10:46:54 INFO - Running setup.py install for functools32 10:46:54 INFO - warning: no files found matching '*.txt' 10:46:54 INFO - no previously-included directories found matching 'build' 10:46:54 INFO - no previously-included directories found matching 'dist' 10:46:54 INFO - no previously-included directories found matching '.git*' 10:46:54 INFO - Successfully installed jsonschema functools32 10:46:54 INFO - Cleaning up... 10:46:54 INFO - Return code: 0 10:46:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:46:54 INFO - Using env: {'DISPLAY': ':0', 10:46:54 INFO - 'HOME': '/home/cltbld', 10:46:54 INFO - 'LANG': 'en_US.UTF-8', 10:46:54 INFO - 'LANGUAGE': 'en_US:en', 10:46:54 INFO - 'LOGNAME': 'cltbld', 10:46:54 INFO - 'MAIL': '/var/mail/cltbld', 10:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:54 INFO - 'MOZ_NO_REMOTE': '1', 10:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:54 INFO - 'NO_EM_RESTART': '1', 10:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:54 INFO - 'PWD': '/builds/slave/test', 10:46:54 INFO - 'SHELL': '/bin/bash', 10:46:54 INFO - 'SHLVL': '1', 10:46:54 INFO - 'TERM': 'linux', 10:46:54 INFO - 'TMOUT': '86400', 10:46:54 INFO - 'USER': 'cltbld', 10:46:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:54 INFO - '_': '/tools/buildbot/bin/python'} 10:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:54 INFO - Downloading/unpacking blobuploader==1.2.4 10:46:54 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 10:46:54 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 10:46:54 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 10:46:54 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 10:46:54 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 10:46:54 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 10:46:56 INFO - Downloading blobuploader-1.2.4.tar.gz 10:46:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:46:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:46:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:46:57 INFO - Downloading docopt-0.6.1.tar.gz 10:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:46:57 INFO - Installing collected packages: blobuploader, requests, docopt 10:46:57 INFO - Running setup.py install for blobuploader 10:46:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:46:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:46:57 INFO - Running setup.py install for requests 10:46:57 INFO - Running setup.py install for docopt 10:46:57 INFO - Successfully installed blobuploader requests docopt 10:46:57 INFO - Cleaning up... 10:46:57 INFO - Return code: 0 10:46:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:46: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')]} 10:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46: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')]} 10:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:46:57 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:46:57 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 10:46:57 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 10:46:57 INFO - Using env: {'DISPLAY': ':0', 10:46:57 INFO - 'HOME': '/home/cltbld', 10:46:57 INFO - 'LANG': 'en_US.UTF-8', 10:46:57 INFO - 'LANGUAGE': 'en_US:en', 10:46:57 INFO - 'LOGNAME': 'cltbld', 10:46:57 INFO - 'MAIL': '/var/mail/cltbld', 10:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:57 INFO - 'MOZ_NO_REMOTE': '1', 10:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:46:57 INFO - 'NO_EM_RESTART': '1', 10:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:57 INFO - 'PWD': '/builds/slave/test', 10:46:57 INFO - 'SHELL': '/bin/bash', 10:46:57 INFO - 'SHLVL': '1', 10:46:57 INFO - 'TERM': 'linux', 10:46:57 INFO - 'TMOUT': '86400', 10:46:57 INFO - 'USER': 'cltbld', 10:46:57 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:57 INFO - '_': '/tools/buildbot/bin/python'} 10:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:46:58 INFO - Running setup.py (path:/tmp/pip-tBeI3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:46:58 INFO - Running setup.py (path:/tmp/pip-uHiXQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:46:58 INFO - Running setup.py (path:/tmp/pip-achnsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:46:58 INFO - Running setup.py (path:/tmp/pip-yPbWrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:46:58 INFO - Running setup.py (path:/tmp/pip-HGbrF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:46:58 INFO - Running setup.py (path:/tmp/pip-sEGHv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:46:58 INFO - Running setup.py (path:/tmp/pip-ASTlk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:46:58 INFO - Running setup.py (path:/tmp/pip-Jp3kmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:46:58 INFO - Running setup.py (path:/tmp/pip-RlkbG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:46:58 INFO - Running setup.py (path:/tmp/pip-Fnuhf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:46:58 INFO - Running setup.py (path:/tmp/pip-PsNJQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:46:58 INFO - Running setup.py (path:/tmp/pip-cUiKnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:46:58 INFO - Running setup.py (path:/tmp/pip-jby6UH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:46:59 INFO - Running setup.py (path:/tmp/pip-x3XJ4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:46:59 INFO - Running setup.py (path:/tmp/pip-nc5a5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:46:59 INFO - Running setup.py (path:/tmp/pip-ehr3Pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:46:59 INFO - Running setup.py (path:/tmp/pip-VnGfa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:46:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:46:59 INFO - Running setup.py install for manifestparser 10:46:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:46:59 INFO - Running setup.py install for mozcrash 10:46:59 INFO - Running setup.py install for mozdebug 10:46:59 INFO - Running setup.py install for mozdevice 10:46:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:46:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:46:59 INFO - Running setup.py install for mozfile 10:46:59 INFO - Running setup.py install for mozhttpd 10:46:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:46:59 INFO - Running setup.py install for mozinfo 10:47:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for mozInstall 10:47:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for mozleak 10:47:00 INFO - Running setup.py install for mozlog 10:47:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for moznetwork 10:47:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for mozprocess 10:47:00 INFO - Running setup.py install for mozprofile 10:47:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for mozrunner 10:47:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:47:00 INFO - Running setup.py install for mozscreenshot 10:47:00 INFO - Running setup.py install for moztest 10:47:00 INFO - Running setup.py install for mozversion 10:47:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:47:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:47:01 INFO - Cleaning up... 10:47:01 INFO - Return code: 0 10:47:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:01 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:47:01 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 10:47:01 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 10:47:01 INFO - Using env: {'DISPLAY': ':0', 10:47:01 INFO - 'HOME': '/home/cltbld', 10:47:01 INFO - 'LANG': 'en_US.UTF-8', 10:47:01 INFO - 'LANGUAGE': 'en_US:en', 10:47:01 INFO - 'LOGNAME': 'cltbld', 10:47:01 INFO - 'MAIL': '/var/mail/cltbld', 10:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:01 INFO - 'MOZ_NO_REMOTE': '1', 10:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:01 INFO - 'NO_EM_RESTART': '1', 10:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:01 INFO - 'PWD': '/builds/slave/test', 10:47:01 INFO - 'SHELL': '/bin/bash', 10:47:01 INFO - 'SHLVL': '1', 10:47:01 INFO - 'TERM': 'linux', 10:47:01 INFO - 'TMOUT': '86400', 10:47:01 INFO - 'USER': 'cltbld', 10:47:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:01 INFO - '_': '/tools/buildbot/bin/python'} 10:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:01 INFO - Running setup.py (path:/tmp/pip-T0qc9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:01 INFO - Running setup.py (path:/tmp/pip-sOZSNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:01 INFO - Running setup.py (path:/tmp/pip-rbLe9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:01 INFO - Running setup.py (path:/tmp/pip-CJw66S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:01 INFO - Running setup.py (path:/tmp/pip-usa7Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:01 INFO - Running setup.py (path:/tmp/pip-Dv66hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:01 INFO - Running setup.py (path:/tmp/pip-NEkAK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:01 INFO - Running setup.py (path:/tmp/pip-9KoUOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:01 INFO - Running setup.py (path:/tmp/pip-oLFFcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:01 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)) 10:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:01 INFO - Running setup.py (path:/tmp/pip-TrTqZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:02 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:02 INFO - Running setup.py (path:/tmp/pip-Oxh3jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:02 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:02 INFO - Running setup.py (path:/tmp/pip-PsNLVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:02 INFO - Running setup.py (path:/tmp/pip-qZmzII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:02 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:02 INFO - Running setup.py (path:/tmp/pip-4nJR2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:02 INFO - Running setup.py (path:/tmp/pip-an4YI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:02 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:02 INFO - Running setup.py (path:/tmp/pip-LuUQxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:02 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)) 10:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:02 INFO - Running setup.py (path:/tmp/pip-R7eqT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:02 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)) 10:47:02 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)) 10:47:02 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)) 10:47:02 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)) 10:47:02 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)) 10:47:02 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)) 10:47:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:47:02 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 10:47:02 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 10:47:02 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 10:47:02 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 10:47:02 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 10:47:02 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 10:47:04 INFO - Downloading blessings-1.6.tar.gz 10:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:47:04 INFO - Installing collected packages: blessings 10:47:04 INFO - Running setup.py install for blessings 10:47:04 INFO - Successfully installed blessings 10:47:04 INFO - Cleaning up... 10:47:04 INFO - Return code: 0 10:47:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:47:04 INFO - Reading from file tmpfile_stdout 10:47:04 INFO - Current package versions: 10:47:04 INFO - argparse == 1.2.1 10:47:04 INFO - blessings == 1.6 10:47:04 INFO - blobuploader == 1.2.4 10:47:04 INFO - docopt == 0.6.1 10:47:04 INFO - functools32 == 3.2.3-2 10:47:04 INFO - jsonschema == 2.5.1 10:47:04 INFO - manifestparser == 1.1 10:47:04 INFO - mozInstall == 1.12 10:47:04 INFO - mozcrash == 0.17 10:47:04 INFO - mozdebug == 0.1 10:47:04 INFO - mozdevice == 0.48 10:47:04 INFO - mozfile == 1.2 10:47:04 INFO - mozhttpd == 0.7 10:47:04 INFO - mozinfo == 0.9 10:47:04 INFO - mozleak == 0.1 10:47:04 INFO - mozlog == 3.2 10:47:04 INFO - moznetwork == 0.27 10:47:04 INFO - mozprocess == 0.23 10:47:04 INFO - mozprofile == 0.28 10:47:04 INFO - mozrunner == 6.12 10:47:04 INFO - mozscreenshot == 0.1 10:47:04 INFO - mozsystemmonitor == 0.3 10:47:04 INFO - moztest == 0.7 10:47:04 INFO - mozversion == 1.4 10:47:04 INFO - psutil == 3.1.1 10:47:04 INFO - requests == 1.2.3 10:47:04 INFO - wsgiref == 0.1.2 10:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:04 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:47:04 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 10:47:04 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 10:47:04 INFO - Using env: {'DISPLAY': ':0', 10:47:04 INFO - 'HOME': '/home/cltbld', 10:47:04 INFO - 'LANG': 'en_US.UTF-8', 10:47:04 INFO - 'LANGUAGE': 'en_US:en', 10:47:04 INFO - 'LOGNAME': 'cltbld', 10:47:04 INFO - 'MAIL': '/var/mail/cltbld', 10:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:04 INFO - 'MOZ_NO_REMOTE': '1', 10:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:04 INFO - 'NO_EM_RESTART': '1', 10:47:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:04 INFO - 'PWD': '/builds/slave/test', 10:47:04 INFO - 'SHELL': '/bin/bash', 10:47:04 INFO - 'SHLVL': '1', 10:47:04 INFO - 'TERM': 'linux', 10:47:04 INFO - 'TMOUT': '86400', 10:47:04 INFO - 'USER': 'cltbld', 10:47:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:04 INFO - '_': '/tools/buildbot/bin/python'} 10:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 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)) 10:47:05 INFO - Cleaning up... 10:47:05 INFO - Return code: 0 10:47:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:47:05 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')]} 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:05 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')]} 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:05 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:47:05 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 10:47:05 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 10:47:05 INFO - Using env: {'DISPLAY': ':0', 10:47:05 INFO - 'HOME': '/home/cltbld', 10:47:05 INFO - 'LANG': 'en_US.UTF-8', 10:47:05 INFO - 'LANGUAGE': 'en_US:en', 10:47:05 INFO - 'LOGNAME': 'cltbld', 10:47:05 INFO - 'MAIL': '/var/mail/cltbld', 10:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:05 INFO - 'MOZ_NO_REMOTE': '1', 10:47:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:05 INFO - 'NO_EM_RESTART': '1', 10:47:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:05 INFO - 'PWD': '/builds/slave/test', 10:47:05 INFO - 'SHELL': '/bin/bash', 10:47:05 INFO - 'SHLVL': '1', 10:47:05 INFO - 'TERM': 'linux', 10:47:05 INFO - 'TMOUT': '86400', 10:47:05 INFO - 'USER': 'cltbld', 10:47:05 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:05 INFO - '_': '/tools/buildbot/bin/python'} 10:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:47:05 INFO - Cleaning up... 10:47:05 INFO - Return code: 0 10:47:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:47:05 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')]} 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:05 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')]} 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:05 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 0x7f44459a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x128e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, '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': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', '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 10:47:05 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 10:47:05 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 10:47:05 INFO - Using env: {'DISPLAY': ':0', 10:47:05 INFO - 'HOME': '/home/cltbld', 10:47:05 INFO - 'LANG': 'en_US.UTF-8', 10:47:05 INFO - 'LANGUAGE': 'en_US:en', 10:47:05 INFO - 'LOGNAME': 'cltbld', 10:47:05 INFO - 'MAIL': '/var/mail/cltbld', 10:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:05 INFO - 'MOZ_NO_REMOTE': '1', 10:47:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:05 INFO - 'NO_EM_RESTART': '1', 10:47:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:05 INFO - 'PWD': '/builds/slave/test', 10:47:05 INFO - 'SHELL': '/bin/bash', 10:47:05 INFO - 'SHLVL': '1', 10:47:05 INFO - 'TERM': 'linux', 10:47:05 INFO - 'TMOUT': '86400', 10:47:05 INFO - 'USER': 'cltbld', 10:47:05 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:05 INFO - '_': '/tools/buildbot/bin/python'} 10:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:05 INFO - Downloading/unpacking flake8 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:47:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:47:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:47:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:47:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:47:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:47:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:47:08 INFO - no previously-included directories found matching 'docs/_build' 10:47:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:47:08 INFO - Running setup.py install for pep8 10:47:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:47:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:47:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:47:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:47:08 INFO - no previously-included directories found matching 'docs/_build' 10:47:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:47:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:47:08 INFO - Cleaning up... 10:47:08 INFO - Return code: 0 10:47:08 INFO - Running post-action listener: _resource_record_post_action 10:47:08 INFO - Running post-action listener: _start_resource_monitoring 10:47:08 INFO - Starting resource monitoring. 10:47:08 INFO - ##### 10:47:08 INFO - ##### Finished create-virtualenv step (success) 10:47:08 INFO - ##### 10:47:08 INFO - ##### 10:47:08 INFO - ##### Running install step. 10:47:08 INFO - ##### 10:47:08 INFO - Running pre-action listener: _resource_record_pre_action 10:47:08 INFO - Running main action method: install 10:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:47:08 INFO - Reading from file tmpfile_stdout 10:47:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:47:08 INFO - Reading from file tmpfile_stdout 10:47:08 INFO - Output received: 10:47:08 INFO - Usage: mozinstall [options] installer 10:47:08 INFO - Options: 10:47:08 INFO - -h, --help show this help message and exit 10:47:08 INFO - -d DEST, --destination=DEST 10:47:08 INFO - Directory to install application into. [default: 10:47:08 INFO - "/builds/slave/test"] 10:47:08 INFO - --app=APP Application being installed. [default: firefox] 10:47:08 INFO - mkdir: /builds/slave/test/build/application 10:47:08 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'] 10:47:08 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 10:47:24 INFO - Reading from file tmpfile_stdout 10:47:24 INFO - Output received: 10:47:24 INFO - /builds/slave/test/build/application/firefox/firefox 10:47:24 INFO - Running post-action listener: _resource_record_post_action 10:47:24 INFO - ##### 10:47:24 INFO - ##### Finished install step (success) 10:47:24 INFO - ##### 10:47:24 INFO - ##### 10:47:24 INFO - ##### Running run-tests step. 10:47:24 INFO - ##### 10:47:24 INFO - Running pre-action listener: _resource_record_pre_action 10:47:24 INFO - Running main action method: run_tests 10:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:47:24 INFO - Python 2.7.3 10:47:24 INFO - Return code: 0 10:47:24 INFO - grabbing minidump binary from tooltool 10:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:47:24 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 0x131d1c0>, '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 0x131e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131e550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:47:24 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 10:47:24 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 10:47:24 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 10:47:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:47:24 INFO - Return code: 0 10:47:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:47:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:47:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:47:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:47:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:47:25 INFO - Return code: 0 10:47:25 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/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 10:47:25 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/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 10:47:25 INFO - Using env: {'DISPLAY': ':0', 10:47:25 INFO - 'HOME': '/home/cltbld', 10:47:25 INFO - 'LANG': 'en_US.UTF-8', 10:47:25 INFO - 'LANGUAGE': 'en_US:en', 10:47:25 INFO - 'LOGNAME': 'cltbld', 10:47:25 INFO - 'MAIL': '/var/mail/cltbld', 10:47:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:47:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:25 INFO - 'MOZ_NO_REMOTE': '1', 10:47:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:47:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:25 INFO - 'NO_EM_RESTART': '1', 10:47:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:25 INFO - 'PWD': '/builds/slave/test', 10:47:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:47:25 INFO - 'SHELL': '/bin/bash', 10:47:25 INFO - 'SHLVL': '1', 10:47:25 INFO - 'TERM': 'linux', 10:47:25 INFO - 'TMOUT': '86400', 10:47:25 INFO - 'USER': 'cltbld', 10:47:25 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469727987.373595-1041378210', 10:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:25 INFO - '_': '/tools/buildbot/bin/python'} 10:47:25 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/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 10:47:25 INFO - mozversion application_buildid: 20160728070523 10:47:25 INFO - mozversion application_changeset: db8d264de7e3ed62616a1b36bf7a037273faea58 10:47:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:47:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:47:25 INFO - mozversion application_name: Firefox 10:47:25 INFO - mozversion application_remotingname: firefox-dev 10:47:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:47:25 INFO - mozversion application_vendor: Mozilla 10:47:25 INFO - mozversion application_version: 49.0a2 10:47:25 INFO - mozversion platform_buildid: 20160728070523 10:47:25 INFO - mozversion platform_changeset: db8d264de7e3ed62616a1b36bf7a037273faea58 10:47:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:47:25 INFO - mozversion platform_version: 49.0a2 10:47:25 INFO - using testdate: 1469728045 10:47:25 INFO - actual date: 1469728045 10:47:25 INFO - starting webserver on 'localhost:59985' 10:47:25 INFO - SUITE-START | Running 2 tests 10:47:25 INFO - TEST-START | dromaeo_css 10:47:25 INFO - operating with platform_type : linux_ 10:47:25 INFO - Initialising browser for dromaeo_css test... 10:47:25 INFO - TEST-INFO | started process 4874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg3nTCs/profile http://localhost:59985/getInfo.html) 10:47:30 INFO - PROCESS | 4874 | __metrics Screen width/height:1600/1200 10:47:30 INFO - PROCESS | 4874 | colorDepth:24 10:47:30 INFO - PROCESS | 4874 | Browser inner width/height: 1024/697 10:47:30 INFO - PROCESS | 4874 | __metrics 10:47:31 INFO - PROCESS | 4874 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:47:31 ERROR - PROCESS | 4874 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:47:32 INFO - TEST-INFO | 4874: exit 0 10:47:32 INFO - Browser initialized. 10:47:32 INFO - Running cycle 1/1 for dromaeo_css test... 10:47:32 INFO - TEST-INFO | started process 4966 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg3nTCs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:47:43 INFO - PROCESS | 4966 | RSS: Main: 145412096 10:47:43 INFO - PROCESS | 4966 | 10:48:34 INFO - PROCESS | 4966 | 1469728114649 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] 10:50:43 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59985/tests/dromaeo/cssquery-ext.html) 10:50:44 INFO - PROCESS | 4966 | RSS: Main: 252968960 10:50:44 INFO - PROCESS | 4966 | 10:53:46 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-ext.html (next: http://localhost:59985/tests/dromaeo/cssquery-jquery.html) 10:53:47 INFO - PROCESS | 4966 | RSS: Main: 228163584 10:53:47 INFO - PROCESS | 4966 | 10:56:48 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59985/tests/dromaeo/cssquery-mootools.html) 10:56:49 INFO - PROCESS | 4966 | RSS: Main: 232484864 10:56:49 INFO - PROCESS | 4966 | 10:59:51 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59985/tests/dromaeo/cssquery-prototype.html) 10:59:52 INFO - PROCESS | 4966 | RSS: Main: 218181632 10:59:52 INFO - PROCESS | 4966 | 11:02:54 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59985/tests/dromaeo/cssquery-yui.html) 11:02:55 INFO - PROCESS | 4966 | RSS: Main: 204566528 11:02:55 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | Cycle 1(1): loaded http://localhost:59985/tests/dromaeo/cssquery-yui.html (next: http://localhost:59985/tests/dromaeo/cssquery-dojo.html) 11:05:57 INFO - PROCESS | 4966 | RSS: Main: 260276224 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | __start_tp_report 11:05:57 INFO - PROCESS | 4966 | _x_x_mozilla_page_load 11:05:57 INFO - PROCESS | 4966 | _x_x_mozilla_page_load_details 11:05:57 INFO - PROCESS | 4966 | |i|pagename|runs| 11:05:57 INFO - PROCESS | 4966 | |0;dojo.html;3376.2403459221678;3378.527006219128;3396.9150771230716;3403.37037648035;3437.2953544523375;5411.188321751734;5503.174523821429;5541.556675465963;5543.390227075019;5573.665580065199;5567.053600887849;5610.831675049745;5612.045952188128;5621.100623900177;5660.801871934473;4964.87587810304;5054.267131265973;5077.619178561623;5116.948830511685;5164.1995490699;4930.679505832128;5022.598192144638;5038.672486288379;5078.238264260372;5169.767360468769;5055.671381360218;5064.392272927235;5091.643584949055;5159.1229490986625;5167.612429067816;5094.31226784383;5147.176451767699;5162.354705661793;5166.7763223478305;5187.766550505236;4866.902661946777;5017.071841709259;5030.974845125787;5042.924356134661;5057.468965758616;5535.778568857241;5540.556755459554;5570.582206334541;5582.441755824426;5593.888122237573;6047.885726790755;6064.9090263646085;6073.1497590337385;6077.17958075663;6082.9391706082815;5791.305043394777;5931.555133364972;5966.0454327307425;5973.313068997078;6064.787732429344;5637.210790489335;5654.802081927154;5694.572907031989;5696.715164241813;5697.686627235459;5857.092150716646;6169.876602468015;6171.197744293213;6183.783567575113;6186.690665466709;5814.825555233349;5850.970745146332;5862.618929769636;5899.46904778572;5940.554458415636;5945.108233764883;5973.820785376446;5978.222831031938;5983.431574000463;5986.132010858401;5752.597318187771;5940.108983652487;5942.64344139353;5955.493783028404;5960.374160713062;5832.795852145155;5885.323187833368;5899.439553242435;5924.200232968494;5938.970305148533;4551.158035763395;4561.2473941799235;4587.7017993830295;4589.173948689268;4614.792334344963;4040.8181631826637;4087.346024636044;4113.979430102891;4127.814248358831;4149.979250103791;5721.284839395075;5773.942260577424;5788.479036886616;5797.333306669787;5829.7376618052285;6056.727447264883;6069.089636554552;6070.119832624245;6071.392860713893;6107;5498.945010549924;5630.07103827872;5670.659760414388;5753.194552762617;5764.279465066867;5078.847634570895;5101.285819985205;5103.8468845934685;5103.897922041539;5114.258432527263;6260.280067792171;6270.529710271748;6278.6546739929745;6286.654234017173;6288.874222515524;5747;5936.406359364029;5942.6731529766275;5948.226730525004;6039.124326972652;6223.394986433035;6238.844028899314;6245;6247.875042499216;6256.124142620128;5887.676177810262;5938.8218353449465;5957.672328022;5960.046392577166;5982.1325907744;5796.304443466811;5937.34689184198;5947.226860508106;5992.280926288873;6056.939430605814;5864.589478736448;5903.144044113666;5905.793297234576;5908.734106965283;5910.261217347832;5562.249096371938;5890.617109887844;5900.173975643499;5900.527957763455;5908.822735317947;5704.030314846403;5710.628809127394;5752.6836024019085;5797.913031304618;5806.767729290781;5890.970545147246;5919.437653422918;5920.2895652521975;5935.495482884004;5958.940410595838;5620.85947851307;5625.212470254086;5807.070868661163;5811.622244554091;5831.271091113611 11:05:57 INFO - PROCESS | 4966 | |1;ext.html;597.635442380148;652.7421668440966;655.8425977765338;658.8814013477576;669.1167658690531;33057.50413743793;45888;46817.76591117044;46848.06303873929;46879.06241875161;47884.76057619707;48318;48418.27372589414;48478;48532.27201591979;8252.33981281496;8980.8652870207;9173.816523669522;10822;11146.6098686546;10970.725731856688;11085.889141108606;11087.72280692981;11093.3898635575;11095.223334366598;11031.669049928474;11200.663980080612;11202.495887685074;11203.327800332007;11209.047230985376;8161.673533058671;10824.18818588609;10948.069414099811;10967.725806854813;11385.14611404148;10964.780704385947;11033.448327583588;11034.503447344889;11037.227394082418;11038.724031899186;44429.11141777179;44842.10315793666;44850;44884.55115448869;44884.55115448869;3790.8483660653815;3817.2938006468894;4116.6912481564;4277.443932288814;4552.180607490651;32796.508052379235;34536.481952770475;35361.64638353609;35786.8210658945;37331.25337493235;4708.387909571768;4726.26742854858;4778.713277203344;4797.928031079537;4875.084671474691;367146;371144.9999999973;374340;374491;375308;39035.41446878299;39040.21919561593;70732.17071317193;72373;72495.27504724886;42205.155896882505;42215.57784422117;42343;42432.15135697269;42706.78646606747;14952.70094598102;16433.91783041077;16590.253438595293;16624.750628740578;16703.248353824107;13398.531051413154;13652.795208071886;13676.521321753691;13952.16287022782;13957;9318.974912759591;9363.765905852408;9424.387414818015;9459.19596834263;9489.335746497816;3547.94678079829;3565.5899571549085;3603.135247540624;3615.5299811024397;3666.0651533859227;1044.9738756530996;1055.6832950115088;1181.8168183931505;1197.9936853043484;1215.6899990502366;33724;33780.66219337775;33865;33920.49119263213;34066.82966585151;1156.9991956957172;1175.9176857619884;1183.7336599265097;1223.7919553675897;1304.0806231606693;17339.306427742747;17992.56059515262;18093.170646505423;18118.5470363242;18192.545186370182;651707;660291;660318;661096;662870;63694.36305636977;65007.349926501076;65111;65132.67433662801;65149.348506514325;1410.4217270918873;1492.9925350373396;1503.5940296119986;1506.4049700368294;1507.4121092773828;33242.50136248006;34292.65707342895;34352.82823585866;35117.534389692;35731.821340893126;54339.18491222556;54788.726056369465;54846.451535484135;54852.45147548473;54936.725316373166;458527;459441;459938;460005;461068;27130.593041104003;30277.848610756806;30627.693723062483;30739.077827665205;32421.837890810395;51152;53374.199387010005;60112.69943650254;60199.09701354396;60732.392676072675;45752.313715294964;48062.759686201345;49145;49180.50819491902;50423.24365134453;45477.77261113673;45522.54477455183;46798.298025528966;47687.76156119336;49159;46446.30330545112;47095.52904470912;48205.27692084692;48798;48870.26694599513;41728.37407438952;42845.57154428416;43223.783881080395;43453.34819977766;43660;29933.850330748206;30531.847340763154;30636.540451892793;32723;33220.33559328848 11:05:57 INFO - PROCESS | 4966 | |2;jquery.html;401.8151650240888;411.77907504249913;412.9359949207872;417.9582041795819;430.10967297693765;18277.36029238977;19349.129289181987;19767.308144214956;19768;20326.593468130628;10867.83698244527;11024.614138505154;11046.33721976682;11821.34982575958;12102.878971210284;4725.503822098682;4984.850454486371;5026.346574945252;5037.5718063964605;5046.394432668085;4849.393825771778;4907.484714105021;5026.673266237701;5028.421731500869;5033.84898453047;4984.501549845023;4989.226669866177;5007.2489126630935;5009.223570346602;5017.949820501784;4953.950460495403;4992.950070499285;5010.624203184758;5071.619628527875;5083.288339632454;4933.038057578774;4939.160342741742;4940.209566469348;4948.134076536628;4962.6774259673;14482.565523034325;22401.21595744141;23210.535789284288;23250.76749232503;23333.766662333328;2289.5993201189726;2392.4018995251186;2466.432720474301;2469.962950555743;2807.1297897651793;9651.372660777028;9721.368111072758;9915.107640312406;10009.099181073738;12192.146549741521;1803.9098045097824;1864.1704441523527;1893.933712320062;1945.367755479475;1967.1934506852265;54134.72932635351;67534.32465675379;70933.29066709318;71970.64014679946;80511.59744201241;10651.414172220602;10839.945800270949;11039.503222354853;11122.832102629107;11222.719432014264;15664.92167539155;15687;15699.058056516646;15727.292271847795;15929.840701593068;7977.122516523178;8000.679972801023;8045.396595255379;8105.675772969015;8137.308328792119;7616.5810880401;7657.731979380695;7668.884966725504;7759.0689117305155;7790.57151856642;4477.664175186874;4553.5218802025975;4580.335851301609;4619.376384188159;4715.976420117878;2035.6132334856627;2074.263636409083;2076.6988786625857;2077.7091207231;2078.2622169130045;947.677789551542;980.720494659033;986.0927946289431;1015.4212099103588;1086.3536195963388;18636.906815465834;19080.332188373617;19176.232950682097;19271.13279902408;19596.608067838562;1019.8521214423866;1043.5825669732017;1056.6513050693252;1067.5356220044305;1110.6612483192541;10425.635102771366;10719.732006699738;10874.945625271823;10916.781664366668;11032.338059716443;522781;541569;546544;565513;570326;91895;92336;96562;96772.51613741886;96980.51509742407;1010.3382284603597;1024.7540590258284;1026.594495174402;1031.3450958641317;1068.7274744940146;16635.50093497197;17065.744013839554;17137.400190993256;17146.485605431855;17631;38902.4164637525;38969;39276;40794.592054079076;40837;53426.46573534371;54777;54809.45190548203;54946.45053549573;55615.72192139013;7638.350740187147;7917.469516597092;8090.676372945016;8094.959525202336;8168.591570421574;12731.23612583248;13002.544910928073;13144.0799144059;13242.801357979446;13310.267935263653;10708;10708.089812366035;10878.129749619853;11036;11093.611723589636;11435.59975400857;11530;11558.711032224262;11867.406629668309;12023.098267630137;11063.668089957315;11078.778424431628;11205.887941120485;11385.943070284595;11394.316341019565;7253.093363329584;7359.484836061639;7447.180810110992;7459.067616547932;8182.549959752271;10746.570137194738;11075.612353567587;11089;11175.385353805294;11397.430128493708 11:05:57 INFO - PROCESS | 4966 | |3;mootools.html;1422.5163444428892;1593.378582352882;1625.561098503404;1833.495788658119;1945.280246308866;9285.675001374953;9662.420254784718;9715.271354648403;9984.051515106072;10014.649487267947;9621.951890240556;9834.40993540388;9868.950655246732;9869.7532561686;9875.703728888127;2373.2761507740174;2396.4847557775074;2399.5560821248037;2405.4226985523524;2409.783119519243;2331.591971405006;2338.2985104468676;2365.2667673021333;2368;2369.8104151667826;2293.2317673579373;2298.6666933294664;2310.4339436838;2325.6046472099783;2334.3930578049744;2241.9327420177256;2254.7407048189502;2257.9435514112115;2271.6024695678275;2273.8521996070285;2242.4057624729458;2246.8202543796456;2247.4269061389405;2248.7413947396094;2248.865068095903;9101.271898248124;9372;9643.90356096437;9699.854502182403;9701.272404569685;5444.891102177974;5575.219469274305;5576.609637325389;5577.749001294951;5580.693061881595;3494.52823868777;3519;3535.292941411728;3570;3596.226811235571;1254.8431446069242;1258.9685257868628;1265.316728966357;1266.018835402561;1274.9043821713408;13839.308034598229;13907.721845563134;13922.09506382082;13940;13993.230372329517;4796.616270698336;4840.056189043174;4862.562369386772;4908.950910490921;4941.085899108676;10580.894191058145;10723;10743.677689669323;10745.892541074645;10792.568297268179;5380.838574842761;5434.864128396822;5446.836594902159;5457.2905522282645;5459.972700136474;4381.496127945264;4770.9980904010545;5039.798408063636;5073.518015788494;5141.871453213699;4077.7757223352405;4116.75299482032;4123.793810309473;4150.128473020639;4232.407462955189;1185.3184418959133;1220.2678392964328;1236.8991597478253;1241.975160496785;1244.8568414632252;824.7649419915296;991.1872264742985;1019.2152042927053;1029.351508549609;1041.833306670929;6101.816945491642;6149.600275982137;6370.490360771128;6376.33048529907;6408.55140140195;1002.2683441088046;1041.3127335958382;1062.4634559547378;1067.1196263082988;1087.8422628718793;4189.874303770892;4318.870433886988;4325.372820941009;4351.956480435155;4362.825486980485;12118.939405303094;12420.62738117858;12506.49974001048;12568.937155314165;12574.245545267313;11556.248843825124;12114.394280285951;12169.939150304192;12303.56937507183;12401.875981240071;9011.27909767217;9102.453852768722;9134.451932884049;9159.954200228956;9224.953875230714;6229.657368844757;6363.204599425072;6397.168368112116;6421.935780642134;6466.7736629219635;11003.17476189273;11368.829467557827;11384.658460246206;11442.828357574477;11546.94226528862;9710.563024664048;11421.828672570084;11600.6519804406;11618.128640351839;11624.186306958432;8093.150219226896;8377.70678026266;8398.580070996548;8574.399792014749;8590.14098590136;9213.815723685622;9307.953460232655;9448.858267125861;9509.476978766234;9545.093216144454;8413.831723365376;8560.272376848037;8624.439411438236;8643.092475290256;8698.21716045559;8253.380996425172;8358;8391.58042097905;8403.537805420761;8643.308535317285;7609.162992070757;7695.836907302903;7713.807154821175;7818.843623127392;7848.097468790934;5165;5203.297554830049;5530.419305972976;5605.271314729059;5676.403977582295;5622.297212848394;5648.096304591246;5702.287214098238;5776.393478684846;5807.651540907559 11:05:57 INFO - PROCESS | 4966 | |4;prototype.html;313.21070901328653;315.84207896051976;321.1633694226541;321.27392093867866;322.5210562314962;5402.864928376793;5408.945910540893;5410.48600382964;5413.700711829158;5482.862928426792;5324.281222035024;5328.294945617395;5354;5382.434844341336;5397.163439666858;4136.503619565657;4206.894827629303;4219.7046206765535;4223.746575205498;4242.9151416971645;4175.582441755831;4181.456410666609;4182.811773470186;4205.558416366283;4206.938600085967;4117.258893399187;4146.792660366985;4159.3968874513175;4159.688023398242;4160.604742549468;3928.6071392860626;3960.881173564798;4085.2850751118435;4142.97928510357;4167.708260421772;3572.374834403969;3572.99956012317;3589.5513060867393;3596.5504311961004;3612.5123108380294;5164.25118357836;5166.405863325691;5188.610854185951;5219.895602087975;5231.764570594332;4494.460664720222;4567.2920697291975;4706.9764651176865;4726.385569875929;4750.786214620351;5633.802816901389;5633.971830140864;5635.943640563582;5647.37878833328;5657.971710141423;3997.5402828674783;4011.2178125265586;4014.3777714454013;4020.4170395292517;4025.4766880305665;5788.942110578924;5888.175655408247;5891.469767720926;5921.61509501881;5931.584789064767;4046.433499310099;4063.4311196432523;4080.18396320737;4081.3673880548563;4086.4483294755423;4152.086540960984;4153.8961525962095;4154.231467178582;4155.646770024582;4158.106007208465;4582.564656357641;4698.929516057262;4736.934189807266;4740.6444516660695;4798.544138306925;5621.353544342453;5637.577181711388;5659.952908711902;5660.943390566124;5708.885822283614;1394.5816255123423;1402.3128666953323;1402.656349194454;1403.8488439479734;1410.8871290296956;882.9646814127491;893.2496702769574;910.5492781073411;927.675313640234;929.2473096791621;5504.256925315111;5511.283533140666;5517.420670829585;5541.5289700375915;5561.777528898799;5766.394528574439;5770.740316685759;5775.393583673654;5785.305763308345;5796.739146738407;5370.301860758076;5377.892442151135;5451.182322651634;5505.587080968943;5521.171824226398;3088.861001254949;3115.5482455043893;3151.401233765577;3157.5579418881375;3162.335909459039;5902.61632993854;6026.337102918676;6042.939570604326;6091.360407157184;6103.969480152571;5936.970315148483;5942.286925568959;5947.316058653224;5965.164876917235;5978.910316345258;6036.185115009417;6106.3283038866575;6107.068607068543;6110.694465276719;6149.692515374124;5763.691633029318;5815.41845815415;5915.290165180206;5921.779332750219;5962.165296858357;5768.625039372428;5775.133729940542;5794.478496935296;5799.391063938226;5822.097574875901;5741.8564535886935;6104.38956104386;6108.8778224436155;6132.325444201224;6154.630722156685;4037.454943582696;4056.492938382702;4064.878053658395;4071.491063617048;4085.8774236772942;5888.998870192163;5891.644921667955;5913.349531551612;5913.408659134052;5918.141869428822;5453.209284653623;5727.742251598771;5735.369109398046;5742.569307301886;5775.566832487664;5717.885642287214;5727.284089488814;5734.7132643368495;5740.6268592541355;5742.3347229303035;5647.576431767552;5662.405447428126;5683.460071293221;5683.914741278967;5715.971420142872;5620.690862002629;5651.865343861584;5761.625494342854;5802.332731735904;5814.633561113051;5584.553235741049;5630.74661640219;5669.461401166883;5673.461021202979;5706.857328566819 11:05:57 INFO - PROCESS | 4966 | |5;yui.html;799.4044436894511;805.9637316320768;876.7588913048911;902.2511315608044;915.9908400915997;14891.925540372296;15705.450309239179;16418.261178246994;16760.916195419035;16975.15124243789;16030.43893463729;16323.83676163238;16354.918225408886;16361.83638163618;16366.83633163668;732.6117157906318;879.8944126704788;888.8044630181382;888.9244414224798;889.1020069729555;833.3250067445358;855.0765173612515;855.529458797662;861.1646702698373;865.5974971638187;915.0163574157774;917.5962576466367;919.4529255093242;919.5264438814016;919.586186216202;753.427395179662;905.6377449020379;912.5254867468913;912.5528491039377;913.4245425382001;678.8235058884676;678.9185297764251;680.5406350713298;680.938715515606;684.2815044203616;15636.2181890905;15794;15830.129342886137;15838.76241856373;15859.44491942788;14864.777028344584;15446.382144714198;15487.845121548753;15543.689126217525;15611.375544978187;14669.706605867823;14716.502003729782;14842.109473431628;14926.992405617619;15069.321880515294;4720.315554244616;4728.952710472886;4760.309755085494;4802.59177969873;4906.582940450066;66395.3360466394;68421.315786842;83470.58264708698;83518.58240708818;83681.58159209165;12217.51129954792;12220.694482638006;12314.445849936774;12363.123973584792;12526.311052891997;22046.669299960515;22233.555328893333;22341.441463963205;22380.328590142322;22389.552208956055;8950.283977281802;9064.320175986828;9089.409188402866;9103;9434.858477122849;4739.170645137087;5030.3963524377305;5154.278401023859;5169.069567477815;5185.455527169668;3677.5035370225214;3774.584795672529;3776.773393596393;3778.0932576182067;3785.564660064073;638.6742761191733;643.1189270699078;658.1181217169017;663.7212370804274;670.637855558003;792.2077922077922;814.1573471457014;828.7513745876213;835.6323217784277;860.3160487412522;10699.946500267604;10736.624218152327;10766.838497422545;10776.353418794897;10797;717.5730440387961;773.0491495460615;788.2905385153408;806.8749343851713;826.5867066466767;4687.742174180385;4746.5490778376;4842.661013729005;4906.9263961040615;4926.531979461945;98904.50547747359;108179.45910270399;111287;112079;113565;65487;79925.20074799295;97292.51353743186;98791;99207;87992.56003719941;88926;89574.55212723895;90275.54862225647;90873;9841.311108222355;9986.450745208793;10437.582496700046;10539.841902371318;10550.736231594272;46771;54010;54189.45810542002;55335.16997245125;55561.444385557246;51209.48790512047;62000;71693.641531792;74221;76259.61870190613;13839.377228024561;13939.163650180615;14157.929210353883;14211.786823197453;14562.41750329975;13896.58310250694;13921.303934803016;14310.856891430953;14896.255187240378;15284.235788210322;10169.79660406773;13585.728285434037;13702.246376449391;14513.854861451251;15179.089254644756;14124.617261208416;14346.354414051184;14653.706925861636;14743.852561474248;15048.623784405478;14147.200095468144;14328;14801.407943682132;14809.92595037018;14922.85077149258;10860.728481787703;11124.332540047299;11265.098792096776;11338.433078345884;11382.203245772716;11777.35224562623;12177.512899484276;12209.132025637702;12230.571929982763;12350.691232719253 11:05:57 INFO - PROCESS | 4966 | __end_tp_report 11:05:57 INFO - PROCESS | 4966 | __start_cc_report 11:05:57 INFO - PROCESS | 4966 | _x_x_mozilla_cycle_collect,885 11:05:57 INFO - PROCESS | 4966 | __end_cc_report 11:05:57 INFO - PROCESS | 4966 | __startTimestamp1469729157910__endTimestamp 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | ------- Summary: start ------- 11:05:57 INFO - PROCESS | 4966 | Number of tests: 6 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#0] dojo.html Cycles:165 Average:5567.41 Median:5796.30 stddev:620.32 (10.7%) stddev-sans-first:597.93 11:05:57 INFO - PROCESS | 4966 | Values: 3376.2 3378.5 3396.9 3403.4 3437.3 5411.2 5503.2 5541.6 5543.4 5573.7 5567.1 5610.8 5612.0 5621.1 5660.8 4964.9 5054.3 5077.6 5116.9 5164.2 4930.7 5022.6 5038.7 5078.2 5169.8 5055.7 5064.4 5091.6 5159.1 5167.6 5094.3 5147.2 5162.4 5166.8 5187.8 4866.9 5017.1 5031.0 5042.9 5057.5 5535.8 5540.6 5570.6 5582.4 5593.9 6047.9 6064.9 6073.1 6077.2 6082.9 5791.3 5931.6 5966.0 5973.3 6064.8 5637.2 5654.8 5694.6 5696.7 5697.7 5857.1 6169.9 6171.2 6183.8 6186.7 5814.8 5851.0 5862.6 5899.5 5940.6 5945.1 5973.8 5978.2 5983.4 5986.1 5752.6 5940.1 5942.6 5955.5 5960.4 5832.8 5885.3 5899.4 5924.2 5939.0 4551.2 4561.2 4587.7 4589.2 4614.8 4040.8 4087.3 4114.0 4127.8 4150.0 5721.3 5773.9 5788.5 5797.3 5829.7 6056.7 6069.1 6070.1 6071.4 6107.0 5498.9 5630.1 5670.7 5753.2 5764.3 5078.8 5101.3 5103.8 5103.9 5114.3 6260.3 6270.5 6278.7 6286.7 6288.9 5747.0 5936.4 5942.7 5948.2 6039.1 6223.4 6238.8 6245.0 6247.9 6256.1 5887.7 5938.8 5957.7 5960.0 5982.1 5796.3 5937.3 5947.2 5992.3 6056.9 5864.6 5903.1 5905.8 5908.7 5910.3 5562.2 5890.6 5900.2 5900.5 5908.8 5704.0 5710.6 5752.7 5797.9 5806.8 5891.0 5919.4 5920.3 5935.5 5958.9 5620.9 5625.2 5807.1 5811.6 5831.3 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#1] ext.html Cycles:180 Average:66266.20 Median:21578.46 stddev:136259.16 (631.5%) stddev-sans-first:136552.19 11:05:57 INFO - PROCESS | 4966 | Values: 597.6 652.7 655.8 658.9 669.1 33057.5 45888.0 46817.8 46848.1 46879.1 47884.8 48318.0 48418.3 48478.0 48532.3 8252.3 8980.9 9173.8 10822.0 11146.6 10970.7 11085.9 11087.7 11093.4 11095.2 11031.7 11200.7 11202.5 11203.3 11209.0 8161.7 10824.2 10948.1 10967.7 11385.1 10964.8 11033.4 11034.5 11037.2 11038.7 44429.1 44842.1 44850.0 44884.6 44884.6 3790.8 3817.3 4116.7 4277.4 4552.2 32796.5 34536.5 35361.6 35786.8 37331.3 4708.4 4726.3 4778.7 4797.9 4875.1 367146.0 371145.0 374340.0 374491.0 375308.0 39035.4 39040.2 70732.2 72373.0 72495.3 42205.2 42215.6 42343.0 42432.2 42706.8 14952.7 16433.9 16590.3 16624.8 16703.2 13398.5 13652.8 13676.5 13952.2 13957.0 9319.0 9363.8 9424.4 9459.2 9489.3 3547.9 3565.6 3603.1 3615.5 3666.1 1045.0 1055.7 1181.8 1198.0 1215.7 33724.0 33780.7 33865.0 33920.5 34066.8 1157.0 1175.9 1183.7 1223.8 1304.1 17339.3 17992.6 18093.2 18118.5 18192.5 651707.0 660291.0 660318.0 661096.0 662870.0 63694.4 65007.3 65111.0 65132.7 65149.3 1410.4 1493.0 1503.6 1506.4 1507.4 33242.5 34292.7 34352.8 35117.5 35731.8 54339.2 54788.7 54846.5 54852.5 54936.7 458527.0 459441.0 459938.0 460005.0 461068.0 27130.6 30277.8 30627.7 30739.1 32421.8 51152.0 53374.2 60112.7 60199.1 60732.4 45752.3 48062.8 49145.0 49180.5 50423.2 45477.8 45522.5 46798.3 47687.8 49159.0 46446.3 47095.5 48205.3 48798.0 48870.3 41728.4 42845.6 43223.8 43453.3 43660.0 29933.9 30531.8 30636.5 32723.0 33220.3 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#2] jquery.html Cycles:180 Average:29798.18 Median:2429.42 stddev:90289.58 (3716.5%) stddev-sans-first:90515.88 11:05:57 INFO - PROCESS | 4966 | Values: 401.8 411.8 412.9 418.0 430.1 18277.4 19349.1 19767.3 19768.0 20326.6 10867.8 11024.6 11046.3 11821.3 12102.9 4725.5 4984.9 5026.3 5037.6 5046.4 4849.4 4907.5 5026.7 5028.4 5033.8 4984.5 4989.2 5007.2 5009.2 5017.9 4954.0 4993.0 5010.6 5071.6 5083.3 4933.0 4939.2 4940.2 4948.1 4962.7 14482.6 22401.2 23210.5 23250.8 23333.8 2289.6 2392.4 2466.4 2470.0 2807.1 9651.4 9721.4 9915.1 10009.1 12192.1 1803.9 1864.2 1893.9 1945.4 1967.2 54134.7 67534.3 70933.3 71970.6 80511.6 10651.4 10839.9 11039.5 11122.8 11222.7 15664.9 15687.0 15699.1 15727.3 15929.8 7977.1 8000.7 8045.4 8105.7 8137.3 7616.6 7657.7 7668.9 7759.1 7790.6 4477.7 4553.5 4580.3 4619.4 4716.0 2035.6 2074.3 2076.7 2077.7 2078.3 947.7 980.7 986.1 1015.4 1086.4 18636.9 19080.3 19176.2 19271.1 19596.6 1019.9 1043.6 1056.7 1067.5 1110.7 10425.6 10719.7 10874.9 10916.8 11032.3 522781.0 541569.0 546544.0 565513.0 570326.0 91895.0 92336.0 96562.0 96772.5 96980.5 1010.3 1024.8 1026.6 1031.3 1068.7 16635.5 17065.7 17137.4 17146.5 17631.0 38902.4 38969.0 39276.0 40794.6 40837.0 53426.5 54777.0 54809.5 54946.5 55615.7 7638.4 7917.5 8090.7 8095.0 8168.6 12731.2 13002.5 13144.1 13242.8 13310.3 10708.0 10708.1 10878.1 11036.0 11093.6 11435.6 11530.0 11558.7 11867.4 12023.1 11063.7 11078.8 11205.9 11385.9 11394.3 7253.1 7359.5 7447.2 7459.1 8182.5 10746.6 11075.6 11089.0 11175.4 11397.4 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#3] mootools.html Cycles:180 Average:6299.08 Median:4338.66 stddev:3730.45 (86.0%) stddev-sans-first:3722.92 11:05:57 INFO - PROCESS | 4966 | Values: 1422.5 1593.4 1625.6 1833.5 1945.3 9285.7 9662.4 9715.3 9984.1 10014.6 9622.0 9834.4 9869.0 9869.8 9875.7 2373.3 2396.5 2399.6 2405.4 2409.8 2331.6 2338.3 2365.3 2368.0 2369.8 2293.2 2298.7 2310.4 2325.6 2334.4 2241.9 2254.7 2257.9 2271.6 2273.9 2242.4 2246.8 2247.4 2248.7 2248.9 9101.3 9372.0 9643.9 9699.9 9701.3 5444.9 5575.2 5576.6 5577.7 5580.7 3494.5 3519.0 3535.3 3570.0 3596.2 1254.8 1259.0 1265.3 1266.0 1274.9 13839.3 13907.7 13922.1 13940.0 13993.2 4796.6 4840.1 4862.6 4909.0 4941.1 10580.9 10723.0 10743.7 10745.9 10792.6 5380.8 5434.9 5446.8 5457.3 5460.0 4381.5 4771.0 5039.8 5073.5 5141.9 4077.8 4116.8 4123.8 4150.1 4232.4 1185.3 1220.3 1236.9 1242.0 1244.9 824.8 991.2 1019.2 1029.4 1041.8 6101.8 6149.6 6370.5 6376.3 6408.6 1002.3 1041.3 1062.5 1067.1 1087.8 4189.9 4318.9 4325.4 4352.0 4362.8 12118.9 12420.6 12506.5 12568.9 12574.2 11556.2 12114.4 12169.9 12303.6 12401.9 9011.3 9102.5 9134.5 9160.0 9225.0 6229.7 6363.2 6397.2 6421.9 6466.8 11003.2 11368.8 11384.7 11442.8 11546.9 9710.6 11421.8 11600.7 11618.1 11624.2 8093.2 8377.7 8398.6 8574.4 8590.1 9213.8 9308.0 9448.9 9509.5 9545.1 8413.8 8560.3 8624.4 8643.1 8698.2 8253.4 8358.0 8391.6 8403.5 8643.3 7609.2 7695.8 7713.8 7818.8 7848.1 5165.0 5203.3 5530.4 5605.3 5676.4 5622.3 5648.1 5702.3 5776.4 5807.7 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#4] prototype.html Cycles:180 Average:4770.09 Median:5508.44 stddev:1435.54 (26.1%) stddev-sans-first:1400.05 11:05:57 INFO - PROCESS | 4966 | Values: 313.2 315.8 321.2 321.3 322.5 5402.9 5408.9 5410.5 5413.7 5482.9 5324.3 5328.3 5354.0 5382.4 5397.2 4136.5 4206.9 4219.7 4223.7 4242.9 4175.6 4181.5 4182.8 4205.6 4206.9 4117.3 4146.8 4159.4 4159.7 4160.6 3928.6 3960.9 4085.3 4143.0 4167.7 3572.4 3573.0 3589.6 3596.6 3612.5 5164.3 5166.4 5188.6 5219.9 5231.8 4494.5 4567.3 4707.0 4726.4 4750.8 5633.8 5634.0 5635.9 5647.4 5658.0 3997.5 4011.2 4014.4 4020.4 4025.5 5788.9 5888.2 5891.5 5921.6 5931.6 4046.4 4063.4 4080.2 4081.4 4086.4 4152.1 4153.9 4154.2 4155.6 4158.1 4582.6 4698.9 4736.9 4740.6 4798.5 5621.4 5637.6 5660.0 5660.9 5708.9 1394.6 1402.3 1402.7 1403.8 1410.9 883.0 893.2 910.5 927.7 929.2 5504.3 5511.3 5517.4 5541.5 5561.8 5766.4 5770.7 5775.4 5785.3 5796.7 5370.3 5377.9 5451.2 5505.6 5521.2 3088.9 3115.5 3151.4 3157.6 3162.3 5902.6 6026.3 6042.9 6091.4 6104.0 5937.0 5942.3 5947.3 5965.2 5978.9 6036.2 6106.3 6107.1 6110.7 6149.7 5763.7 5815.4 5915.3 5921.8 5962.2 5768.6 5775.1 5794.5 5799.4 5822.1 5741.9 6104.4 6108.9 6132.3 6154.6 4037.5 4056.5 4064.9 4071.5 4085.9 5889.0 5891.6 5913.3 5913.4 5918.1 5453.2 5727.7 5735.4 5742.6 5775.6 5717.9 5727.3 5734.7 5740.6 5742.3 5647.6 5662.4 5683.5 5683.9 5716.0 5620.7 5651.9 5761.6 5802.3 5814.6 5584.6 5630.7 5669.5 5673.5 5706.9 11:05:57 INFO - PROCESS | 4966 | 11:05:57 INFO - PROCESS | 4966 | [#5] yui.html Cycles:180 Average:20759.48 Median:4734.06 stddev:28617.03 (604.5%) stddev-sans-first:28658.06 11:05:57 INFO - PROCESS | 4966 | Values: 799.4 806.0 876.8 902.3 916.0 14891.9 15705.5 16418.3 16760.9 16975.2 16030.4 16323.8 16354.9 16361.8 16366.8 732.6 879.9 888.8 888.9 889.1 833.3 855.1 855.5 861.2 865.6 915.0 917.6 919.5 919.5 919.6 753.4 905.6 912.5 912.6 913.4 678.8 678.9 680.5 680.9 684.3 15636.2 15794.0 15830.1 15838.8 15859.4 14864.8 15446.4 15487.8 15543.7 15611.4 14669.7 14716.5 14842.1 14927.0 15069.3 4720.3 4729.0 4760.3 4802.6 4906.6 66395.3 68421.3 83470.6 83518.6 83681.6 12217.5 12220.7 12314.4 12363.1 12526.3 22046.7 22233.6 22341.4 22380.3 22389.6 8950.3 9064.3 9089.4 9103.0 9434.9 4739.2 5030.4 5154.3 5169.1 5185.5 3677.5 3774.6 3776.8 3778.1 3785.6 638.7 643.1 658.1 663.7 670.6 792.2 814.2 828.8 835.6 860.3 10699.9 10736.6 10766.8 10776.4 10797.0 717.6 773.0 788.3 806.9 826.6 4687.7 4746.5 4842.7 4906.9 4926.5 98904.5 108179.5 111287.0 112079.0 113565.0 65487.0 79925.2 97292.5 98791.0 99207.0 87992.6 88926.0 89574.6 90275.5 90873.0 9841.3 9986.5 10437.6 10539.8 10550.7 46771.0 54010.0 54189.5 55335.2 55561.4 51209.5 62000.0 71693.6 74221.0 76259.6 13839.4 13939.2 14157.9 14211.8 14562.4 13896.6 13921.3 14310.9 14896.3 15284.2 10169.8 13585.7 13702.2 14513.9 15179.1 14124.6 14346.4 14653.7 14743.9 15048.6 14147.2 14328.0 14801.4 14809.9 14922.9 10860.7 11124.3 11265.1 11338.4 11382.2 11777.4 12177.5 12209.1 12230.6 12350.7 11:05:57 INFO - PROCESS | 4966 | -------- Summary: end -------- 11:05:57 INFO - PROCESS | 4966 | 11:05:58 INFO - TEST-INFO | 4966: exit 0 11:05:58 INFO - TEST-OK | dromaeo_css | took 1112771ms 11:05:58 INFO - TEST-START | kraken 11:05:58 INFO - operating with platform_type : linux_ 11:05:58 INFO - Initialising browser for kraken test... 11:05:58 INFO - TEST-INFO | started process 5154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3VCC78/profile http://localhost:59985/getInfo.html) 11:06:02 INFO - PROCESS | 5154 | __metrics Screen width/height:1600/1200 11:06:02 INFO - PROCESS | 5154 | colorDepth:24 11:06:02 INFO - PROCESS | 5154 | Browser inner width/height: 1024/697 11:06:02 INFO - PROCESS | 5154 | __metrics 11:06:03 INFO - PROCESS | 5154 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:06:03 ERROR - PROCESS | 5154 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:06:04 INFO - TEST-INFO | 5154: exit 0 11:06:04 INFO - Browser initialized. 11:06:04 INFO - Running cycle 1/1 for kraken test... 11:06:04 INFO - TEST-INFO | started process 5247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3VCC78/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:16 INFO - PROCESS | 5247 | RSS: Main: 145563648 11:06:16 INFO - PROCESS | 5247 | 11:06:18 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59985/tests/kraken/driver.html?testName=audio-beat-detection) 11:06:19 INFO - PROCESS | 5247 | RSS: Main: 194813952 11:06:19 INFO - PROCESS | 5247 | 11:06:22 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59985/tests/kraken/driver.html?testName=audio-dft) 11:06:22 INFO - PROCESS | 5247 | RSS: Main: 192663552 11:06:22 INFO - PROCESS | 5247 | 11:06:25 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59985/tests/kraken/driver.html?testName=audio-fft) 11:06:26 INFO - PROCESS | 5247 | RSS: Main: 279064576 11:06:26 INFO - PROCESS | 5247 | 11:06:28 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59985/tests/kraken/driver.html?testName=audio-oscillator) 11:06:29 INFO - PROCESS | 5247 | RSS: Main: 186773504 11:06:29 INFO - PROCESS | 5247 | 11:06:31 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:06:31 INFO - PROCESS | 5247 | RSS: Main: 187920384 11:06:31 INFO - PROCESS | 5247 | 11:06:34 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59985/tests/kraken/driver.html?testName=imaging-darkroom) 11:06:35 INFO - PROCESS | 5247 | RSS: Main: 221949952 11:06:35 INFO - PROCESS | 5247 | 11:06:38 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59985/tests/kraken/driver.html?testName=imaging-desaturate) 11:06:39 INFO - PROCESS | 5247 | RSS: Main: 221941760 11:06:39 INFO - PROCESS | 5247 | 11:06:42 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59985/tests/kraken/driver.html?testName=json-parse-financial) 11:06:42 INFO - PROCESS | 5247 | RSS: Main: 221974528 11:06:42 INFO - PROCESS | 5247 | 11:06:44 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:06:44 INFO - PROCESS | 5247 | RSS: Main: 201035776 11:06:44 INFO - PROCESS | 5247 | 11:06:46 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:06:47 INFO - PROCESS | 5247 | RSS: Main: 202969088 11:06:47 INFO - PROCESS | 5247 | 11:06:49 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:06:49 INFO - PROCESS | 5247 | RSS: Main: 208814080 11:06:49 INFO - PROCESS | 5247 | 11:06:52 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:06:52 INFO - PROCESS | 5247 | RSS: Main: 214237184 11:06:52 INFO - PROCESS | 5247 | 11:06:55 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:06:56 INFO - PROCESS | 5247 | RSS: Main: 214769664 11:06:56 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | Cycle 1(1): loaded http://localhost:59985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59985/tests/kraken/driver.html?testName=ai-astar) 11:06:57 INFO - PROCESS | 5247 | RSS: Main: 257236992 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | __start_tp_report 11:06:57 INFO - PROCESS | 5247 | _x_x_mozilla_page_load 11:06:57 INFO - PROCESS | 5247 | _x_x_mozilla_page_load_details 11:06:57 INFO - PROCESS | 5247 | |i|pagename|runs| 11:06:57 INFO - PROCESS | 5247 | |0;ai-astar;109;105;110;111;111;110;110;112;110;109 11:06:57 INFO - PROCESS | 5247 | |1;audio-beat-detection;153;124;124;171;124;126;154;133;158;123 11:06:57 INFO - PROCESS | 5247 | |2;audio-dft;160;162;160;160;161;161;160;159;161;160 11:06:57 INFO - PROCESS | 5247 | |3;audio-fft;74;75;75;105;75;76;74;75;97;74 11:06:57 INFO - PROCESS | 5247 | |4;audio-oscillator;84;84;110;84;83;84;113;85;84;122 11:06:57 INFO - PROCESS | 5247 | |5;imaging-gaussian-blur;116;117;116;116;116;116;117;117;117;117 11:06:57 INFO - PROCESS | 5247 | |6;imaging-darkroom;145;145;145;145;146;146;145;146;146;145 11:06:57 INFO - PROCESS | 5247 | |7;imaging-desaturate;96;95;94;96;94;95;95;94;95;95 11:06:57 INFO - PROCESS | 5247 | |8;json-parse-financial;50;53;52;51;53;52;52;51;51;53 11:06:57 INFO - PROCESS | 5247 | |9;json-stringify-tinderbox;60;61;60;60;61;60;61;60;60;60 11:06:57 INFO - PROCESS | 5247 | |10;stanford-crypto-aes;76;77;74;81;82;78;77;76;112;79 11:06:57 INFO - PROCESS | 5247 | |11;stanford-crypto-ccm;123;128;122;130;117;117;116;123;126;118 11:06:57 INFO - PROCESS | 5247 | |12;stanford-crypto-pbkdf2;153;155;152;153;152;154;151;152;150;153 11:06:57 INFO - PROCESS | 5247 | |13;stanford-crypto-sha256-iterative;61;60;63;60;60;62;63;60;60;62 11:06:57 INFO - PROCESS | 5247 | __end_tp_report 11:06:57 INFO - PROCESS | 5247 | __start_cc_report 11:06:57 INFO - PROCESS | 5247 | _x_x_mozilla_cycle_collect,1560 11:06:57 INFO - PROCESS | 5247 | __end_cc_report 11:06:57 INFO - PROCESS | 5247 | __startTimestamp1469729217945__endTimestamp 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | ------- Summary: start ------- 11:06:57 INFO - PROCESS | 5247 | Number of tests: 14 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.89 (1.7%) stddev-sans-first:1.99 11:06:57 INFO - PROCESS | 5247 | Values: 109.0 105.0 110.0 111.0 111.0 110.0 110.0 112.0 110.0 109.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:143.00 stddev:18.08 (12.6%) stddev-sans-first:18.45 11:06:57 INFO - PROCESS | 5247 | Values: 153.0 124.0 124.0 171.0 124.0 126.0 154.0 133.0 158.0 123.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 11:06:57 INFO - PROCESS | 5247 | Values: 160.0 162.0 160.0 160.0 161.0 161.0 160.0 159.0 161.0 160.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.00 stddev:11.24 (15.0%) stddev-sans-first:11.72 11:06:57 INFO - PROCESS | 5247 | Values: 74.0 75.0 75.0 105.0 75.0 76.0 74.0 75.0 97.0 74.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:84.00 stddev:15.27 (18.2%) stddev-sans-first:15.82 11:06:57 INFO - PROCESS | 5247 | Values: 84.0 84.0 110.0 84.0 83.0 84.0 113.0 85.0 84.0 122.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 11:06:57 INFO - PROCESS | 5247 | Values: 116.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 11:06:57 INFO - PROCESS | 5247 | Values: 145.0 145.0 145.0 145.0 146.0 146.0 145.0 146.0 146.0 145.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 11:06:57 INFO - PROCESS | 5247 | Values: 96.0 95.0 94.0 96.0 94.0 95.0 95.0 94.0 95.0 95.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:0.87 11:06:57 INFO - PROCESS | 5247 | Values: 50.0 53.0 52.0 51.0 53.0 52.0 52.0 51.0 51.0 53.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 11:06:57 INFO - PROCESS | 5247 | Values: 60.0 61.0 60.0 60.0 61.0 60.0 61.0 60.0 60.0 60.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:77.50 stddev:11.08 (14.3%) stddev-sans-first:11.60 11:06:57 INFO - PROCESS | 5247 | Values: 76.0 77.0 74.0 81.0 82.0 78.0 77.0 76.0 112.0 79.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:123.00 stddev:4.94 (4.0%) stddev-sans-first:5.23 11:06:57 INFO - PROCESS | 5247 | Values: 123.0 128.0 122.0 130.0 117.0 117.0 116.0 123.0 126.0 118.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 11:06:57 INFO - PROCESS | 5247 | Values: 153.0 155.0 152.0 153.0 152.0 154.0 151.0 152.0 150.0 153.0 11:06:57 INFO - PROCESS | 5247 | 11:06:57 INFO - PROCESS | 5247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.50 stddev:1.29 (2.1%) stddev-sans-first:1.36 11:06:57 INFO - PROCESS | 5247 | Values: 61.0 60.0 63.0 60.0 60.0 62.0 63.0 60.0 60.0 62.0 11:06:57 INFO - PROCESS | 5247 | -------- Summary: end -------- 11:06:57 INFO - PROCESS | 5247 | 11:06:58 INFO - TEST-INFO | 5247: exit 0 11:06:58 INFO - TEST-OK | kraken | took 60299ms 11:06:58 INFO - SUITE-END | took 1173s 11:06:59 INFO - Completed test suite (00:19:33) 11:06:59 INFO - javascript benchmark 11:06:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.2403459221678, 3378.527006219128, 3396.9150771230716, 3403.37037648035, 3437.2953544523375, 5411.188321751734, 5503.174523821429, 5541.556675465963, 5543.390227075019, 5573.665580065199, 5567.053600887849, 5610.831675049745, 5612.045952188128, 5621.100623900177, 5660.801871934473, 4964.87587810304, 5054.267131265973, 5077.619178561623, 5116.948830511685, 5164.1995490699, 4930.679505832128, 5022.598192144638, 5038.672486288379, 5078.238264260372, 5169.767360468769, 5055.671381360218, 5064.392272927235, 5091.643584949055, 5159.1229490986625, 5167.612429067816, 5094.31226784383, 5147.176451767699, 5162.354705661793, 5166.7763223478305, 5187.766550505236, 4866.902661946777, 5017.071841709259, 5030.974845125787, 5042.924356134661, 5057.468965758616, 5535.778568857241, 5540.556755459554, 5570.582206334541, 5582.441755824426, 5593.888122237573, 6047.885726790755, 6064.9090263646085, 6073.1497590337385, 6077.17958075663, 6082.9391706082815, 5791.305043394777, 5931.555133364972, 5966.0454327307425, 5973.313068997078, 6064.787732429344, 5637.210790489335, 5654.802081927154, 5694.572907031989, 5696.715164241813, 5697.686627235459, 5857.092150716646, 6169.876602468015, 6171.197744293213, 6183.783567575113, 6186.690665466709, 5814.825555233349, 5850.970745146332, 5862.618929769636, 5899.46904778572, 5940.554458415636, 5945.108233764883, 5973.820785376446, 5978.222831031938, 5983.431574000463, 5986.132010858401, 5752.597318187771, 5940.108983652487, 5942.64344139353, 5955.493783028404, 5960.374160713062, 5832.795852145155, 5885.323187833368, 5899.439553242435, 5924.200232968494, 5938.970305148533, 4551.158035763395, 4561.2473941799235, 4587.7017993830295, 4589.173948689268, 4614.792334344963, 4040.8181631826637, 4087.346024636044, 4113.979430102891, 4127.814248358831, 4149.979250103791, 5721.284839395075, 5773.942260577424, 5788.479036886616, 5797.333306669787, 5829.7376618052285, 6056.727447264883, 6069.089636554552, 6070.119832624245, 6071.392860713893, 6107.0, 5498.945010549924, 5630.07103827872, 5670.659760414388, 5753.194552762617, 5764.279465066867, 5078.847634570895, 5101.285819985205, 5103.8468845934685, 5103.897922041539, 5114.258432527263, 6260.280067792171, 6270.529710271748, 6278.6546739929745, 6286.654234017173, 6288.874222515524, 5747.0, 5936.406359364029, 5942.6731529766275, 5948.226730525004, 6039.124326972652, 6223.394986433035, 6238.844028899314, 6245.0, 6247.875042499216, 6256.124142620128, 5887.676177810262, 5938.8218353449465, 5957.672328022, 5960.046392577166, 5982.1325907744, 5796.304443466811, 5937.34689184198, 5947.226860508106, 5992.280926288873, 6056.939430605814, 5864.589478736448, 5903.144044113666, 5905.793297234576, 5908.734106965283, 5910.261217347832, 5562.249096371938, 5890.617109887844, 5900.173975643499, 5900.527957763455, 5908.822735317947, 5704.030314846403, 5710.628809127394, 5752.6836024019085, 5797.913031304618, 5806.767729290781, 5890.970545147246, 5919.437653422918, 5920.2895652521975, 5935.495482884004, 5958.940410595838, 5620.85947851307, 5625.212470254086, 5807.070868661163, 5811.622244554091, 5831.271091113611], "value": 5527.388525467061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.635442380148, 652.7421668440966, 655.8425977765338, 658.8814013477576, 669.1167658690531, 33057.50413743793, 45888.0, 46817.76591117044, 46848.06303873929, 46879.06241875161, 47884.76057619707, 48318.0, 48418.27372589414, 48478.0, 48532.27201591979, 8252.33981281496, 8980.8652870207, 9173.816523669522, 10822.0, 11146.6098686546, 10970.725731856688, 11085.889141108606, 11087.72280692981, 11093.3898635575, 11095.223334366598, 11031.669049928474, 11200.663980080612, 11202.495887685074, 11203.327800332007, 11209.047230985376, 8161.673533058671, 10824.18818588609, 10948.069414099811, 10967.725806854813, 11385.14611404148, 10964.780704385947, 11033.448327583588, 11034.503447344889, 11037.227394082418, 11038.724031899186, 44429.11141777179, 44842.10315793666, 44850.0, 44884.55115448869, 44884.55115448869, 3790.8483660653815, 3817.2938006468894, 4116.6912481564, 4277.443932288814, 4552.180607490651, 32796.508052379235, 34536.481952770475, 35361.64638353609, 35786.8210658945, 37331.25337493235, 4708.387909571768, 4726.26742854858, 4778.713277203344, 4797.928031079537, 4875.084671474691, 367146.0, 371144.9999999973, 374340.0, 374491.0, 375308.0, 39035.41446878299, 39040.21919561593, 70732.17071317193, 72373.0, 72495.27504724886, 42205.155896882505, 42215.57784422117, 42343.0, 42432.15135697269, 42706.78646606747, 14952.70094598102, 16433.91783041077, 16590.253438595293, 16624.750628740578, 16703.248353824107, 13398.531051413154, 13652.795208071886, 13676.521321753691, 13952.16287022782, 13957.0, 9318.974912759591, 9363.765905852408, 9424.387414818015, 9459.19596834263, 9489.335746497816, 3547.94678079829, 3565.5899571549085, 3603.135247540624, 3615.5299811024397, 3666.0651533859227, 1044.9738756530996, 1055.6832950115088, 1181.8168183931505, 1197.9936853043484, 1215.6899990502366, 33724.0, 33780.66219337775, 33865.0, 33920.49119263213, 34066.82966585151, 1156.9991956957172, 1175.9176857619884, 1183.7336599265097, 1223.7919553675897, 1304.0806231606693, 17339.306427742747, 17992.56059515262, 18093.170646505423, 18118.5470363242, 18192.545186370182, 651707.0, 660291.0, 660318.0, 661096.0, 662870.0, 63694.36305636977, 65007.349926501076, 65111.0, 65132.67433662801, 65149.348506514325, 1410.4217270918873, 1492.9925350373396, 1503.5940296119986, 1506.4049700368294, 1507.4121092773828, 33242.50136248006, 34292.65707342895, 34352.82823585866, 35117.534389692, 35731.821340893126, 54339.18491222556, 54788.726056369465, 54846.451535484135, 54852.45147548473, 54936.725316373166, 458527.0, 459441.0, 459938.0, 460005.0, 461068.0, 27130.593041104003, 30277.848610756806, 30627.693723062483, 30739.077827665205, 32421.837890810395, 51152.0, 53374.199387010005, 60112.69943650254, 60199.09701354396, 60732.392676072675, 45752.313715294964, 48062.759686201345, 49145.0, 49180.50819491902, 50423.24365134453, 45477.77261113673, 45522.54477455183, 46798.298025528966, 47687.76156119336, 49159.0, 46446.30330545112, 47095.52904470912, 48205.27692084692, 48798.0, 48870.26694599513, 41728.37407438952, 42845.57154428416, 43223.783881080395, 43453.34819977766, 43660.0, 29933.850330748206, 30531.847340763154, 30636.540451892793, 32723.0, 33220.33559328848], "value": 21113.63010994873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.8151650240888, 411.77907504249913, 412.9359949207872, 417.9582041795819, 430.10967297693765, 18277.36029238977, 19349.129289181987, 19767.308144214956, 19768.0, 20326.593468130628, 10867.83698244527, 11024.614138505154, 11046.33721976682, 11821.34982575958, 12102.878971210284, 4725.503822098682, 4984.850454486371, 5026.346574945252, 5037.5718063964605, 5046.394432668085, 4849.393825771778, 4907.484714105021, 5026.673266237701, 5028.421731500869, 5033.84898453047, 4984.501549845023, 4989.226669866177, 5007.2489126630935, 5009.223570346602, 5017.949820501784, 4953.950460495403, 4992.950070499285, 5010.624203184758, 5071.619628527875, 5083.288339632454, 4933.038057578774, 4939.160342741742, 4940.209566469348, 4948.134076536628, 4962.6774259673, 14482.565523034325, 22401.21595744141, 23210.535789284288, 23250.76749232503, 23333.766662333328, 2289.5993201189726, 2392.4018995251186, 2466.432720474301, 2469.962950555743, 2807.1297897651793, 9651.372660777028, 9721.368111072758, 9915.107640312406, 10009.099181073738, 12192.146549741521, 1803.9098045097824, 1864.1704441523527, 1893.933712320062, 1945.367755479475, 1967.1934506852265, 54134.72932635351, 67534.32465675379, 70933.29066709318, 71970.64014679946, 80511.59744201241, 10651.414172220602, 10839.945800270949, 11039.503222354853, 11122.832102629107, 11222.719432014264, 15664.92167539155, 15687.0, 15699.058056516646, 15727.292271847795, 15929.840701593068, 7977.122516523178, 8000.679972801023, 8045.396595255379, 8105.675772969015, 8137.308328792119, 7616.5810880401, 7657.731979380695, 7668.884966725504, 7759.0689117305155, 7790.57151856642, 4477.664175186874, 4553.5218802025975, 4580.335851301609, 4619.376384188159, 4715.976420117878, 2035.6132334856627, 2074.263636409083, 2076.6988786625857, 2077.7091207231, 2078.2622169130045, 947.677789551542, 980.720494659033, 986.0927946289431, 1015.4212099103588, 1086.3536195963388, 18636.906815465834, 19080.332188373617, 19176.232950682097, 19271.13279902408, 19596.608067838562, 1019.8521214423866, 1043.5825669732017, 1056.6513050693252, 1067.5356220044305, 1110.6612483192541, 10425.635102771366, 10719.732006699738, 10874.945625271823, 10916.781664366668, 11032.338059716443, 522781.0, 541569.0, 546544.0, 565513.0, 570326.0, 91895.0, 92336.0, 96562.0, 96772.51613741886, 96980.51509742407, 1010.3382284603597, 1024.7540590258284, 1026.594495174402, 1031.3450958641317, 1068.7274744940146, 16635.50093497197, 17065.744013839554, 17137.400190993256, 17146.485605431855, 17631.0, 38902.4164637525, 38969.0, 39276.0, 40794.592054079076, 40837.0, 53426.46573534371, 54777.0, 54809.45190548203, 54946.45053549573, 55615.72192139013, 7638.350740187147, 7917.469516597092, 8090.676372945016, 8094.959525202336, 8168.591570421574, 12731.23612583248, 13002.544910928073, 13144.0799144059, 13242.801357979446, 13310.267935263653, 10708.0, 10708.089812366035, 10878.129749619853, 11036.0, 11093.611723589636, 11435.59975400857, 11530.0, 11558.711032224262, 11867.406629668309, 12023.098267630137, 11063.668089957315, 11078.778424431628, 11205.887941120485, 11385.943070284595, 11394.316341019565, 7253.093363329584, 7359.484836061639, 7447.180810110992, 7459.067616547932, 8182.549959752271, 10746.570137194738, 11075.612353567587, 11089.0, 11175.385353805294, 11397.430128493708], "value": 8829.469217555838, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.5163444428892, 1593.378582352882, 1625.561098503404, 1833.495788658119, 1945.280246308866, 9285.675001374953, 9662.420254784718, 9715.271354648403, 9984.051515106072, 10014.649487267947, 9621.951890240556, 9834.40993540388, 9868.950655246732, 9869.7532561686, 9875.703728888127, 2373.2761507740174, 2396.4847557775074, 2399.5560821248037, 2405.4226985523524, 2409.783119519243, 2331.591971405006, 2338.2985104468676, 2365.2667673021333, 2368.0, 2369.8104151667826, 2293.2317673579373, 2298.6666933294664, 2310.4339436838, 2325.6046472099783, 2334.3930578049744, 2241.9327420177256, 2254.7407048189502, 2257.9435514112115, 2271.6024695678275, 2273.8521996070285, 2242.4057624729458, 2246.8202543796456, 2247.4269061389405, 2248.7413947396094, 2248.865068095903, 9101.271898248124, 9372.0, 9643.90356096437, 9699.854502182403, 9701.272404569685, 5444.891102177974, 5575.219469274305, 5576.609637325389, 5577.749001294951, 5580.693061881595, 3494.52823868777, 3519.0, 3535.292941411728, 3570.0, 3596.226811235571, 1254.8431446069242, 1258.9685257868628, 1265.316728966357, 1266.018835402561, 1274.9043821713408, 13839.308034598229, 13907.721845563134, 13922.09506382082, 13940.0, 13993.230372329517, 4796.616270698336, 4840.056189043174, 4862.562369386772, 4908.950910490921, 4941.085899108676, 10580.894191058145, 10723.0, 10743.677689669323, 10745.892541074645, 10792.568297268179, 5380.838574842761, 5434.864128396822, 5446.836594902159, 5457.2905522282645, 5459.972700136474, 4381.496127945264, 4770.9980904010545, 5039.798408063636, 5073.518015788494, 5141.871453213699, 4077.7757223352405, 4116.75299482032, 4123.793810309473, 4150.128473020639, 4232.407462955189, 1185.3184418959133, 1220.2678392964328, 1236.8991597478253, 1241.975160496785, 1244.8568414632252, 824.7649419915296, 991.1872264742985, 1019.2152042927053, 1029.351508549609, 1041.833306670929, 6101.816945491642, 6149.600275982137, 6370.490360771128, 6376.33048529907, 6408.55140140195, 1002.2683441088046, 1041.3127335958382, 1062.4634559547378, 1067.1196263082988, 1087.8422628718793, 4189.874303770892, 4318.870433886988, 4325.372820941009, 4351.956480435155, 4362.825486980485, 12118.939405303094, 12420.62738117858, 12506.49974001048, 12568.937155314165, 12574.245545267313, 11556.248843825124, 12114.394280285951, 12169.939150304192, 12303.56937507183, 12401.875981240071, 9011.27909767217, 9102.453852768722, 9134.451932884049, 9159.954200228956, 9224.953875230714, 6229.657368844757, 6363.204599425072, 6397.168368112116, 6421.935780642134, 6466.7736629219635, 11003.17476189273, 11368.829467557827, 11384.658460246206, 11442.828357574477, 11546.94226528862, 9710.563024664048, 11421.828672570084, 11600.6519804406, 11618.128640351839, 11624.186306958432, 8093.150219226896, 8377.70678026266, 8398.580070996548, 8574.399792014749, 8590.14098590136, 9213.815723685622, 9307.953460232655, 9448.858267125861, 9509.476978766234, 9545.093216144454, 8413.831723365376, 8560.272376848037, 8624.439411438236, 8643.092475290256, 8698.21716045559, 8253.380996425172, 8358.0, 8391.58042097905, 8403.537805420761, 8643.308535317285, 7609.162992070757, 7695.836907302903, 7713.807154821175, 7818.843623127392, 7848.097468790934, 5165.0, 5203.297554830049, 5530.419305972976, 5605.271314729059, 5676.403977582295, 5622.297212848394, 5648.096304591246, 5702.287214098238, 5776.393478684846, 5807.651540907559], "value": 4955.538829820923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.21070901328653, 315.84207896051976, 321.1633694226541, 321.27392093867866, 322.5210562314962, 5402.864928376793, 5408.945910540893, 5410.48600382964, 5413.700711829158, 5482.862928426792, 5324.281222035024, 5328.294945617395, 5354.0, 5382.434844341336, 5397.163439666858, 4136.503619565657, 4206.894827629303, 4219.7046206765535, 4223.746575205498, 4242.9151416971645, 4175.582441755831, 4181.456410666609, 4182.811773470186, 4205.558416366283, 4206.938600085967, 4117.258893399187, 4146.792660366985, 4159.3968874513175, 4159.688023398242, 4160.604742549468, 3928.6071392860626, 3960.881173564798, 4085.2850751118435, 4142.97928510357, 4167.708260421772, 3572.374834403969, 3572.99956012317, 3589.5513060867393, 3596.5504311961004, 3612.5123108380294, 5164.25118357836, 5166.405863325691, 5188.610854185951, 5219.895602087975, 5231.764570594332, 4494.460664720222, 4567.2920697291975, 4706.9764651176865, 4726.385569875929, 4750.786214620351, 5633.802816901389, 5633.971830140864, 5635.943640563582, 5647.37878833328, 5657.971710141423, 3997.5402828674783, 4011.2178125265586, 4014.3777714454013, 4020.4170395292517, 4025.4766880305665, 5788.942110578924, 5888.175655408247, 5891.469767720926, 5921.61509501881, 5931.584789064767, 4046.433499310099, 4063.4311196432523, 4080.18396320737, 4081.3673880548563, 4086.4483294755423, 4152.086540960984, 4153.8961525962095, 4154.231467178582, 4155.646770024582, 4158.106007208465, 4582.564656357641, 4698.929516057262, 4736.934189807266, 4740.6444516660695, 4798.544138306925, 5621.353544342453, 5637.577181711388, 5659.952908711902, 5660.943390566124, 5708.885822283614, 1394.5816255123423, 1402.3128666953323, 1402.656349194454, 1403.8488439479734, 1410.8871290296956, 882.9646814127491, 893.2496702769574, 910.5492781073411, 927.675313640234, 929.2473096791621, 5504.256925315111, 5511.283533140666, 5517.420670829585, 5541.5289700375915, 5561.777528898799, 5766.394528574439, 5770.740316685759, 5775.393583673654, 5785.305763308345, 5796.739146738407, 5370.301860758076, 5377.892442151135, 5451.182322651634, 5505.587080968943, 5521.171824226398, 3088.861001254949, 3115.5482455043893, 3151.401233765577, 3157.5579418881375, 3162.335909459039, 5902.61632993854, 6026.337102918676, 6042.939570604326, 6091.360407157184, 6103.969480152571, 5936.970315148483, 5942.286925568959, 5947.316058653224, 5965.164876917235, 5978.910316345258, 6036.185115009417, 6106.3283038866575, 6107.068607068543, 6110.694465276719, 6149.692515374124, 5763.691633029318, 5815.41845815415, 5915.290165180206, 5921.779332750219, 5962.165296858357, 5768.625039372428, 5775.133729940542, 5794.478496935296, 5799.391063938226, 5822.097574875901, 5741.8564535886935, 6104.38956104386, 6108.8778224436155, 6132.325444201224, 6154.630722156685, 4037.454943582696, 4056.492938382702, 4064.878053658395, 4071.491063617048, 4085.8774236772942, 5888.998870192163, 5891.644921667955, 5913.349531551612, 5913.408659134052, 5918.141869428822, 5453.209284653623, 5727.742251598771, 5735.369109398046, 5742.569307301886, 5775.566832487664, 5717.885642287214, 5727.284089488814, 5734.7132643368495, 5740.6268592541355, 5742.3347229303035, 5647.576431767552, 5662.405447428126, 5683.460071293221, 5683.914741278967, 5715.971420142872, 5620.690862002629, 5651.865343861584, 5761.625494342854, 5802.332731735904, 5814.633561113051, 5584.553235741049, 5630.74661640219, 5669.461401166883, 5673.461021202979, 5706.857328566819], "value": 4301.835945817914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.4044436894511, 805.9637316320768, 876.7588913048911, 902.2511315608044, 915.9908400915997, 14891.925540372296, 15705.450309239179, 16418.261178246994, 16760.916195419035, 16975.15124243789, 16030.43893463729, 16323.83676163238, 16354.918225408886, 16361.83638163618, 16366.83633163668, 732.6117157906318, 879.8944126704788, 888.8044630181382, 888.9244414224798, 889.1020069729555, 833.3250067445358, 855.0765173612515, 855.529458797662, 861.1646702698373, 865.5974971638187, 915.0163574157774, 917.5962576466367, 919.4529255093242, 919.5264438814016, 919.586186216202, 753.427395179662, 905.6377449020379, 912.5254867468913, 912.5528491039377, 913.4245425382001, 678.8235058884676, 678.9185297764251, 680.5406350713298, 680.938715515606, 684.2815044203616, 15636.2181890905, 15794.0, 15830.129342886137, 15838.76241856373, 15859.44491942788, 14864.777028344584, 15446.382144714198, 15487.845121548753, 15543.689126217525, 15611.375544978187, 14669.706605867823, 14716.502003729782, 14842.109473431628, 14926.992405617619, 15069.321880515294, 4720.315554244616, 4728.952710472886, 4760.309755085494, 4802.59177969873, 4906.582940450066, 66395.3360466394, 68421.315786842, 83470.58264708698, 83518.58240708818, 83681.58159209165, 12217.51129954792, 12220.694482638006, 12314.445849936774, 12363.123973584792, 12526.311052891997, 22046.669299960515, 22233.555328893333, 22341.441463963205, 22380.328590142322, 22389.552208956055, 8950.283977281802, 9064.320175986828, 9089.409188402866, 9103.0, 9434.858477122849, 4739.170645137087, 5030.3963524377305, 5154.278401023859, 5169.069567477815, 5185.455527169668, 3677.5035370225214, 3774.584795672529, 3776.773393596393, 3778.0932576182067, 3785.564660064073, 638.6742761191733, 643.1189270699078, 658.1181217169017, 663.7212370804274, 670.637855558003, 792.2077922077922, 814.1573471457014, 828.7513745876213, 835.6323217784277, 860.3160487412522, 10699.946500267604, 10736.624218152327, 10766.838497422545, 10776.353418794897, 10797.0, 717.5730440387961, 773.0491495460615, 788.2905385153408, 806.8749343851713, 826.5867066466767, 4687.742174180385, 4746.5490778376, 4842.661013729005, 4906.9263961040615, 4926.531979461945, 98904.50547747359, 108179.45910270399, 111287.0, 112079.0, 113565.0, 65487.0, 79925.20074799295, 97292.51353743186, 98791.0, 99207.0, 87992.56003719941, 88926.0, 89574.55212723895, 90275.54862225647, 90873.0, 9841.311108222355, 9986.450745208793, 10437.582496700046, 10539.841902371318, 10550.736231594272, 46771.0, 54010.0, 54189.45810542002, 55335.16997245125, 55561.444385557246, 51209.48790512047, 62000.0, 71693.641531792, 74221.0, 76259.61870190613, 13839.377228024561, 13939.163650180615, 14157.929210353883, 14211.786823197453, 14562.41750329975, 13896.58310250694, 13921.303934803016, 14310.856891430953, 14896.255187240378, 15284.235788210322, 10169.79660406773, 13585.728285434037, 13702.246376449391, 14513.854861451251, 15179.089254644756, 14124.617261208416, 14346.354414051184, 14653.706925861636, 14743.852561474248, 15048.623784405478, 14147.200095468144, 14328.0, 14801.407943682132, 14809.92595037018, 14922.85077149258, 10860.728481787703, 11124.332540047299, 11265.098792096776, 11338.433078345884, 11382.203245772716, 11777.35224562623, 12177.512899484276, 12209.132025637702, 12230.571929982763, 12350.691232719253], "value": 7996.524778142493, "unit": "score"}], "name": "dromaeo_css", "value": 7483.723910611328, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 110.0, 111.0, 111.0, 110.0, 110.0, 112.0, 110.0, 109.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 124.0, 171.0, 124.0, 126.0, 154.0, 133.0, 158.0, 123.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 160.0, 161.0, 161.0, 160.0, 159.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 105.0, 75.0, 76.0, 74.0, 75.0, 97.0, 74.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 110.0, 84.0, 83.0, 84.0, 113.0, 85.0, 84.0, 122.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 53.0, 52.0, 51.0, 53.0, 52.0, 52.0, 51.0, 51.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 74.0, 81.0, 82.0, 78.0, 77.0, 76.0, 112.0, 79.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 122.0, 130.0, 117.0, 117.0, 116.0, 123.0, 126.0, 118.0], "value": 122.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 155.0, 152.0, 153.0, 152.0, 154.0, 151.0, 152.0, 150.0, 153.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 63.0, 60.0, 60.0, 62.0, 63.0, 60.0, 60.0, 62.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1468.1, "alertThreshold": 2.0}]} 11:06:59 INFO - Return code: 0 11:06:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:06:59 ERROR - # TBPL SUCCESS # 11:06:59 INFO - Running post-action listener: _resource_record_post_action 11:06:59 INFO - ##### 11:06:59 INFO - ##### Finished run-tests step (success) 11:06:59 INFO - ##### 11:06:59 INFO - Running post-run listener: _resource_record_post_run 11:06:59 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 505876480; Read time: 16; Write time: 448428 11:06:59 INFO - TinderboxPrint: CPU usage
13.0% 11:06:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 11:06:59 INFO - TinderboxPrint: I/O write bytes / time
505,876,480 / 448,428 11:06:59 INFO - TinderboxPrint: CPU idle
8,257.3 (86.9%) 11:06:59 INFO - TinderboxPrint: CPU user
1,195.6 (12.6%) 11:06:59 INFO - TinderboxPrint: Swap in / out
0 / 0 11:06:59 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252391424; Read time: 0; Write time: 280360 11:06:59 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251863040; Read time: 16; Write time: 167820 11:06:59 INFO - Running post-run listener: _upload_blobber_files 11:06:59 INFO - Blob upload gear active. 11:06:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:06:59 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 11:06:59 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'] 11:06:59 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 11:07:00 INFO - (blobuploader) - INFO - Open directory for files ... 11:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:07:01 INFO - (blobuploader) - INFO - Done attempting. 11:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:07:01 INFO - (blobuploader) - INFO - Done attempting. 11:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:07:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:07:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:07:12 INFO - (blobuploader) - INFO - Done attempting. 11:07:12 INFO - (blobuploader) - INFO - Iteration through files over. 11:07:12 INFO - Return code: 0 11:07:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:07:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:07:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47f5ba68ec919ea4c7036d4788be465ecdb3e7d6707df21e57c90f4782c8a44dc7dedd4a10f7ca50498c5cdedfcdc7a5951c390cb463f42585428ab0bfe30778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e13014b36a41b9ff4a65b15d1d79c305397921e4b580ee23317f65e097c5c972c8b7354340b5267e259daabd218cc16e288613c3c0c89d8637fbbd42714dd05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bfa75928092ae9c439e6953d3c5e9440aac7c49472c35232f11e8483c4f4d363a077d818f4ff99441665af4651deea409eccef108cae880cb4f8f2a90f41488"} 11:07:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:07:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:07:12 INFO - Contents: 11:07:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47f5ba68ec919ea4c7036d4788be465ecdb3e7d6707df21e57c90f4782c8a44dc7dedd4a10f7ca50498c5cdedfcdc7a5951c390cb463f42585428ab0bfe30778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e13014b36a41b9ff4a65b15d1d79c305397921e4b580ee23317f65e097c5c972c8b7354340b5267e259daabd218cc16e288613c3c0c89d8637fbbd42714dd05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bfa75928092ae9c439e6953d3c5e9440aac7c49472c35232f11e8483c4f4d363a077d818f4ff99441665af4651deea409eccef108cae880cb4f8f2a90f41488"} 11:07:12 INFO - Running post-run listener: copy_logs_to_upload_dir 11:07:12 INFO - Copying logs to upload dir... 11:07:12 INFO - mkdir: /builds/slave/test/build/upload/logs 11:07:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.719290 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-28 11:07:12.428042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 11:07:12.431772) ========= 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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 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/47f5ba68ec919ea4c7036d4788be465ecdb3e7d6707df21e57c90f4782c8a44dc7dedd4a10f7ca50498c5cdedfcdc7a5951c390cb463f42585428ab0bfe30778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e13014b36a41b9ff4a65b15d1d79c305397921e4b580ee23317f65e097c5c972c8b7354340b5267e259daabd218cc16e288613c3c0c89d8637fbbd42714dd05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bfa75928092ae9c439e6953d3c5e9440aac7c49472c35232f11e8483c4f4d363a077d818f4ff99441665af4651deea409eccef108cae880cb4f8f2a90f41488"} build_url:https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/RJ1vzjZfS6aZ-cYBAmlcpQ/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/47f5ba68ec919ea4c7036d4788be465ecdb3e7d6707df21e57c90f4782c8a44dc7dedd4a10f7ca50498c5cdedfcdc7a5951c390cb463f42585428ab0bfe30778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e13014b36a41b9ff4a65b15d1d79c305397921e4b580ee23317f65e097c5c972c8b7354340b5267e259daabd218cc16e288613c3c0c89d8637fbbd42714dd05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bfa75928092ae9c439e6953d3c5e9440aac7c49472c35232f11e8483c4f4d363a077d818f4ff99441665af4651deea409eccef108cae880cb4f8f2a90f41488"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 11:07:12.457151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 11:07:12.457487) ========= 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=7de08804244eb36560b44971000001c3-1469727987.373595-1041378210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 11:07:12.543297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 11:07:12.543637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 11:07:12.543978) ========= ========= Total master_lag: 0.18 =========