builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1465986544.55 results: success (0) buildid: 20160615004406 builduid: 06c81a862aaf4d30ac6322cd2823f170 revision: 1a585c67e88e31e89aa165f6b706600a2ef92386 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.551736) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.553656) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.553941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.569432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.570078) ========= 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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-15 03:29:06-- 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% 20.4M=0.001s 2016-06-15 03:29:06 (20.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084955 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.669566) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.669874) ========= 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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021882 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:04.720133) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-15 03:29:04.720484) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1a585c67e88e31e89aa165f6b706600a2ef92386 --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 1a585c67e88e31e89aa165f6b706600a2ef92386 --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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-15 03:29:06,694 truncating revision to first 12 chars 2016-06-15 03:29:06,694 Setting DEBUG logging. 2016-06-15 03:29:06,694 attempt 1/10 2016-06-15 03:29:06,694 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1a585c67e88e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-15 03:29:08,063 unpacking tar archive at: mozilla-aurora-1a585c67e88e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.713611 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-15 03:29:06.448822) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:06.449166) ========= script_repo_revision: 1a585c67e88e31e89aa165f6b706600a2ef92386 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:06.449641) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:06.449917) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 03:29:06.458989) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-15 03:29:06.459341) ========= /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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:29:08 INFO - MultiFileLogger online at 20160615 03:29:08 in /builds/slave/test 03:29:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:29:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:08 INFO - {'append_to_log': False, 03:29:08 INFO - 'base_work_dir': '/builds/slave/test', 03:29:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:29:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:08 INFO - 'branch': 'Mozilla-Aurora', 03:29:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:08 INFO - 'config_files': ('talos/linux_config.py',), 03:29:08 INFO - 'default_actions': ('clobber', 03:29:08 INFO - 'read-buildbot-config', 03:29:08 INFO - 'download-and-extract', 03:29:08 INFO - 'populate-webroot', 03:29:08 INFO - 'create-virtualenv', 03:29:08 INFO - 'install', 03:29:08 INFO - 'run-tests'), 03:29:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:08 INFO - 'download_minidump_stackwalk': True, 03:29:08 INFO - 'download_symbols': 'ondemand', 03:29:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:29:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:29:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:29:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:29:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:08 INFO - 'installer_path': 'installer.exe', 03:29:08 INFO - 'log_level': 'info', 03:29:08 INFO - 'log_name': 'talos', 03:29:08 INFO - 'log_to_console': True, 03:29:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:29:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:29:08 INFO - 'opt_config_files': (), 03:29:08 INFO - 'pip_index': False, 03:29:08 INFO - 'sps_profile': False, 03:29:08 INFO - 'sps_profile_interval': 0, 03:29:08 INFO - 'suite': 'dromaeojs', 03:29:08 INFO - 'system_bits': '32', 03:29:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:08 INFO - 'title': 'talos-linux64-ix-043', 03:29:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:08 INFO - 'use_talos_json': True, 03:29:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:08 INFO - 'work_dir': 'build'} 03:29:08 INFO - ##### 03:29:08 INFO - ##### Running clobber step. 03:29:08 INFO - ##### 03:29:08 INFO - Running pre-action listener: _resource_record_pre_action 03:29:08 INFO - Running main action method: clobber 03:29:08 INFO - rmtree: /builds/slave/test/build 03:29:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:08 INFO - Running post-action listener: _resource_record_post_action 03:29:08 INFO - ##### 03:29:08 INFO - ##### Finished clobber step (success) 03:29:08 INFO - ##### 03:29:08 INFO - ##### 03:29:08 INFO - ##### Running read-buildbot-config step. 03:29:08 INFO - ##### 03:29:08 INFO - Running pre-action listener: _resource_record_pre_action 03:29:08 INFO - Running main action method: read_buildbot_config 03:29:08 INFO - Using buildbot properties: 03:29:08 INFO - { 03:29:08 INFO - "project": "", 03:29:08 INFO - "product": "firefox", 03:29:08 INFO - "script_repo_revision": "production", 03:29:08 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:29:08 INFO - "repository": "", 03:29:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:29:08 INFO - "buildid": "20160615004406", 03:29:08 INFO - "stage_platform": "linux64-pgo", 03:29:08 INFO - "basedir": "/builds/slave/test", 03:29:08 INFO - "nightly_build": "True", 03:29:08 INFO - "buildnumber": 385, 03:29:08 INFO - "slavename": "talos-linux64-ix-043", 03:29:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:29:08 INFO - "platform": "ubuntu64_hw", 03:29:09 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:29:09 INFO - "branch": "mozilla-aurora", 03:29:09 INFO - "repo_path": "releases/mozilla-aurora", 03:29:09 INFO - "revision": "1a585c67e88e31e89aa165f6b706600a2ef92386", 03:29:09 INFO - "pgo_build": "True", 03:29:09 INFO - "builduid": "06c81a862aaf4d30ac6322cd2823f170", 03:29:09 INFO - "slavebuilddir": "test" 03:29:09 INFO - } 03:29:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:29:09 INFO - Running post-action listener: _resource_record_post_action 03:29:09 INFO - ##### 03:29:09 INFO - ##### Finished read-buildbot-config step (success) 03:29:09 INFO - ##### 03:29:09 INFO - ##### 03:29:09 INFO - ##### Running download-and-extract step. 03:29:09 INFO - ##### 03:29:09 INFO - Running pre-action listener: _resource_record_pre_action 03:29:09 INFO - Running main action method: download_and_extract 03:29:09 INFO - mkdir: /builds/slave/test/build/tests 03:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:09 INFO - https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:29:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:29:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:29:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:29:10 INFO - Downloaded 1361 bytes. 03:29:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:29:10 INFO - Using the following test package requirements: 03:29:10 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:29:10 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:29:10 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:29:10 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'jsshell-linux-x86_64.zip'], 03:29:10 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:29:10 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:29:10 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:29:10 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:29:10 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:29:10 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:29:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:29:10 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:10 INFO - https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:29:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:29:11 INFO - Downloaded 24473781 bytes. 03:29:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:29:12 INFO - Return code: 0 03:29:12 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:12 INFO - https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:29:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:29:13 INFO - Downloaded 24473781 bytes. 03:29:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:29:15 INFO - Return code: 0 03:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:15 INFO - https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:29:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:29:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:29:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:29:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:29:17 INFO - Downloaded 10833369 bytes. 03:29:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:29:17 INFO - Return code: 0 03:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:17 INFO - https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:29:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:29:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:29:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:29:27 INFO - Downloaded 63554252 bytes. 03:29:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:29:27 INFO - mkdir: /builds/slave/test/properties 03:29:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:27 INFO - Contents: 03:29:27 INFO - build_url:https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:29:27 INFO - Running post-action listener: _resource_record_post_action 03:29:27 INFO - Running post-action listener: set_extra_try_arguments 03:29:27 INFO - ##### 03:29:27 INFO - ##### Finished download-and-extract step (success) 03:29:27 INFO - ##### 03:29:27 INFO - ##### 03:29:27 INFO - ##### Running populate-webroot step. 03:29:27 INFO - ##### 03:29:27 INFO - Running pre-action listener: _resource_record_pre_action 03:29:27 INFO - Running main action method: populate_webroot 03:29:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:29:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:29:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:29:27 INFO - '--tppagecycles', 03:29:27 INFO - '7'], 03:29:27 INFO - 'tests': ['tsvgm']}}, 03:29:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:29:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:29:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:29:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:29:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['damp', 'tps']}, 03:29:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'tests': ['damp', 'tps']}, 03:29:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['dromaeo_dom']}, 03:29:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['a11yr', 03:29:27 INFO - 'ts_paint', 03:29:27 INFO - 'tpaint', 03:29:27 INFO - 'sessionrestore', 03:29:27 INFO - 'sessionrestore_no_auto_restore', 03:29:27 INFO - 'tabpaint']}, 03:29:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['tsvgx', 03:29:27 INFO - 'tsvgr_opacity', 03:29:27 INFO - 'tart', 03:29:27 INFO - 'tscrollx', 03:29:27 INFO - 'cart']}, 03:29:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:29:27 INFO - 'tsvgr_opacity', 03:29:27 INFO - 'tart', 03:29:27 INFO - 'tscrollx', 03:29:27 INFO - 'cart']}, 03:29:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'talos_options': ['--disable-e10s'], 03:29:27 INFO - 'tests': ['tp5o']}, 03:29:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'tests': ['tp5o']}, 03:29:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'talos_options': ['--disable-e10s', 03:29:27 INFO - '--xperf_path', 03:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:27 INFO - 'tests': ['tp5n']}, 03:29:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:27 INFO - 'talos_options': ['--xperf_path', 03:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:27 INFO - 'tests': ['tp5n']}}, 03:29:27 INFO - 'talos.zip': {'path': '', 03:29:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:29:27 INFO - Running post-action listener: _resource_record_post_action 03:29:27 INFO - ##### 03:29:27 INFO - ##### Finished populate-webroot step (success) 03:29:27 INFO - ##### 03:29:27 INFO - ##### 03:29:27 INFO - ##### Running create-virtualenv step. 03:29:27 INFO - ##### 03:29:27 INFO - Running pre-action listener: _resource_record_pre_action 03:29:27 INFO - Running main action method: create_virtualenv 03:29:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:29:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:29:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:28 INFO - Using real prefix '/usr' 03:29:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:30 INFO - Installing pip.................done. 03:29:30 INFO - Return code: 0 03:29:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:29:30 INFO - Using env: {'DISPLAY': ':0', 03:29:30 INFO - 'HOME': '/home/cltbld', 03:29:30 INFO - 'LANG': 'en_US.UTF-8', 03:29:30 INFO - 'LANGUAGE': 'en_US:en', 03:29:30 INFO - 'LOGNAME': 'cltbld', 03:29:30 INFO - 'MAIL': '/var/mail/cltbld', 03:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:30 INFO - 'MOZ_NO_REMOTE': '1', 03:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:30 INFO - 'NO_EM_RESTART': '1', 03:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:30 INFO - 'PWD': '/builds/slave/test', 03:29:30 INFO - 'SHELL': '/bin/bash', 03:29:30 INFO - 'SHLVL': '1', 03:29:30 INFO - 'TERM': 'linux', 03:29:30 INFO - 'TMOUT': '86400', 03:29:30 INFO - 'USER': 'cltbld', 03:29:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:30 INFO - '_': '/tools/buildbot/bin/python'} 03:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:29:30 INFO - Cleaning up... 03:29:30 INFO - Return code: 0 03:29:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:29:30 INFO - Using env: {'DISPLAY': ':0', 03:29:30 INFO - 'HOME': '/home/cltbld', 03:29:30 INFO - 'LANG': 'en_US.UTF-8', 03:29:30 INFO - 'LANGUAGE': 'en_US:en', 03:29:30 INFO - 'LOGNAME': 'cltbld', 03:29:30 INFO - 'MAIL': '/var/mail/cltbld', 03:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:30 INFO - 'MOZ_NO_REMOTE': '1', 03:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:30 INFO - 'NO_EM_RESTART': '1', 03:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:30 INFO - 'PWD': '/builds/slave/test', 03:29:30 INFO - 'SHELL': '/bin/bash', 03:29:30 INFO - 'SHLVL': '1', 03:29:30 INFO - 'TERM': 'linux', 03:29:30 INFO - 'TMOUT': '86400', 03:29:30 INFO - 'USER': 'cltbld', 03:29:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:30 INFO - '_': '/tools/buildbot/bin/python'} 03:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:30 INFO - Downloading/unpacking psutil>=3.1.1 03:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:33 INFO - Installing collected packages: psutil 03:29:33 INFO - Running setup.py install for psutil 03:29:33 INFO - building 'psutil._psutil_linux' extension 03:29:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:29:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:29:33 INFO - building 'psutil._psutil_posix' extension 03:29:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:29:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:29:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:33 INFO - Successfully installed psutil 03:29:33 INFO - Cleaning up... 03:29:33 INFO - Return code: 0 03:29:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:29:33 INFO - Using env: {'DISPLAY': ':0', 03:29:33 INFO - 'HOME': '/home/cltbld', 03:29:33 INFO - 'LANG': 'en_US.UTF-8', 03:29:33 INFO - 'LANGUAGE': 'en_US:en', 03:29:33 INFO - 'LOGNAME': 'cltbld', 03:29:33 INFO - 'MAIL': '/var/mail/cltbld', 03:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:33 INFO - 'MOZ_NO_REMOTE': '1', 03:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:33 INFO - 'NO_EM_RESTART': '1', 03:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:33 INFO - 'PWD': '/builds/slave/test', 03:29:33 INFO - 'SHELL': '/bin/bash', 03:29:33 INFO - 'SHLVL': '1', 03:29:33 INFO - 'TERM': 'linux', 03:29:33 INFO - 'TMOUT': '86400', 03:29:33 INFO - 'USER': 'cltbld', 03:29:33 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:33 INFO - '_': '/tools/buildbot/bin/python'} 03:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:29:35 INFO - Installing collected packages: mozsystemmonitor 03:29:35 INFO - Running setup.py install for mozsystemmonitor 03:29:35 INFO - Successfully installed mozsystemmonitor 03:29:35 INFO - Cleaning up... 03:29:35 INFO - Return code: 0 03:29:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:35 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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:29:35 INFO - Using env: {'DISPLAY': ':0', 03:29:35 INFO - 'HOME': '/home/cltbld', 03:29:35 INFO - 'LANG': 'en_US.UTF-8', 03:29:35 INFO - 'LANGUAGE': 'en_US:en', 03:29:35 INFO - 'LOGNAME': 'cltbld', 03:29:35 INFO - 'MAIL': '/var/mail/cltbld', 03:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:35 INFO - 'MOZ_NO_REMOTE': '1', 03:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:35 INFO - 'NO_EM_RESTART': '1', 03:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:35 INFO - 'PWD': '/builds/slave/test', 03:29:35 INFO - 'SHELL': '/bin/bash', 03:29:35 INFO - 'SHLVL': '1', 03:29:35 INFO - 'TERM': 'linux', 03:29:35 INFO - 'TMOUT': '86400', 03:29:35 INFO - 'USER': 'cltbld', 03:29:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:35 INFO - '_': '/tools/buildbot/bin/python'} 03:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:36 INFO - Downloading/unpacking jsonschema==2.5.1 03:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:38 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:38 INFO - warning: no files found matching '*.txt' 03:29:38 INFO - no previously-included directories found matching 'build' 03:29:38 INFO - no previously-included directories found matching 'dist' 03:29:38 INFO - no previously-included directories found matching '.git*' 03:29:38 INFO - Installing collected packages: jsonschema, functools32 03:29:38 INFO - Running setup.py install for functools32 03:29:38 INFO - warning: no files found matching '*.txt' 03:29:38 INFO - no previously-included directories found matching 'build' 03:29:38 INFO - no previously-included directories found matching 'dist' 03:29:38 INFO - no previously-included directories found matching '.git*' 03:29:38 INFO - Successfully installed jsonschema functools32 03:29:38 INFO - Cleaning up... 03:29:38 INFO - Return code: 0 03:29:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:38 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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:29:38 INFO - Using env: {'DISPLAY': ':0', 03:29:38 INFO - 'HOME': '/home/cltbld', 03:29:38 INFO - 'LANG': 'en_US.UTF-8', 03:29:38 INFO - 'LANGUAGE': 'en_US:en', 03:29:38 INFO - 'LOGNAME': 'cltbld', 03:29:38 INFO - 'MAIL': '/var/mail/cltbld', 03:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:38 INFO - 'MOZ_NO_REMOTE': '1', 03:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:38 INFO - 'NO_EM_RESTART': '1', 03:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:38 INFO - 'PWD': '/builds/slave/test', 03:29:38 INFO - 'SHELL': '/bin/bash', 03:29:38 INFO - 'SHLVL': '1', 03:29:38 INFO - 'TERM': 'linux', 03:29:38 INFO - 'TMOUT': '86400', 03:29:38 INFO - 'USER': 'cltbld', 03:29:38 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:38 INFO - '_': '/tools/buildbot/bin/python'} 03:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:41 INFO - Downloading docopt-0.6.1.tar.gz 03:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:41 INFO - Running setup.py install for blobuploader 03:29:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:29:41 INFO - Running setup.py install for requests 03:29:41 INFO - Running setup.py install for docopt 03:29:41 INFO - Successfully installed blobuploader requests docopt 03:29:41 INFO - Cleaning up... 03:29:42 INFO - Return code: 0 03:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:42 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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:29:42 INFO - Using env: {'DISPLAY': ':0', 03:29:42 INFO - 'HOME': '/home/cltbld', 03:29:42 INFO - 'LANG': 'en_US.UTF-8', 03:29:42 INFO - 'LANGUAGE': 'en_US:en', 03:29:42 INFO - 'LOGNAME': 'cltbld', 03:29:42 INFO - 'MAIL': '/var/mail/cltbld', 03:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:42 INFO - 'MOZ_NO_REMOTE': '1', 03:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:42 INFO - 'NO_EM_RESTART': '1', 03:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:42 INFO - 'PWD': '/builds/slave/test', 03:29:42 INFO - 'SHELL': '/bin/bash', 03:29:42 INFO - 'SHLVL': '1', 03:29:42 INFO - 'TERM': 'linux', 03:29:42 INFO - 'TMOUT': '86400', 03:29:42 INFO - 'USER': 'cltbld', 03:29:42 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:42 INFO - '_': '/tools/buildbot/bin/python'} 03:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:42 INFO - Running setup.py (path:/tmp/pip-Yi2CHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:42 INFO - Running setup.py (path:/tmp/pip-3GQzPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:42 INFO - Running setup.py (path:/tmp/pip-Ixil1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:42 INFO - Running setup.py (path:/tmp/pip-MOkB6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:42 INFO - Running setup.py (path:/tmp/pip-M3laI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:42 INFO - Running setup.py (path:/tmp/pip-yJEMIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:42 INFO - Running setup.py (path:/tmp/pip-9Dx3xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:42 INFO - Running setup.py (path:/tmp/pip-NGKo83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:42 INFO - Running setup.py (path:/tmp/pip-XYWaWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:42 INFO - Running setup.py (path:/tmp/pip-n7pSyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:42 INFO - Running setup.py (path:/tmp/pip-6O707x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:43 INFO - Running setup.py (path:/tmp/pip-MGISY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:43 INFO - Running setup.py (path:/tmp/pip-K0stqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:43 INFO - Running setup.py (path:/tmp/pip-VeST41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:43 INFO - Running setup.py (path:/tmp/pip-3KV4Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:43 INFO - Running setup.py (path:/tmp/pip-n6gEXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:43 INFO - Running setup.py (path:/tmp/pip-ujvQ4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:43 INFO - Running setup.py install for manifestparser 03:29:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:29:43 INFO - Running setup.py install for mozcrash 03:29:43 INFO - Running setup.py install for mozdebug 03:29:43 INFO - Running setup.py install for mozdevice 03:29:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:29:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:29:43 INFO - Running setup.py install for mozfile 03:29:43 INFO - Running setup.py install for mozhttpd 03:29:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozinfo 03:29:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozInstall 03:29:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozleak 03:29:44 INFO - Running setup.py install for mozlog 03:29:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for moznetwork 03:29:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozprocess 03:29:44 INFO - Running setup.py install for mozprofile 03:29:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozrunner 03:29:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:29:44 INFO - Running setup.py install for mozscreenshot 03:29:45 INFO - Running setup.py install for moztest 03:29:45 INFO - Running setup.py install for mozversion 03:29:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:29:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:45 INFO - Cleaning up... 03:29:45 INFO - Return code: 0 03:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:45 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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:29:45 INFO - Using env: {'DISPLAY': ':0', 03:29:45 INFO - 'HOME': '/home/cltbld', 03:29:45 INFO - 'LANG': 'en_US.UTF-8', 03:29:45 INFO - 'LANGUAGE': 'en_US:en', 03:29:45 INFO - 'LOGNAME': 'cltbld', 03:29:45 INFO - 'MAIL': '/var/mail/cltbld', 03:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:45 INFO - 'MOZ_NO_REMOTE': '1', 03:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:45 INFO - 'NO_EM_RESTART': '1', 03:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:45 INFO - 'PWD': '/builds/slave/test', 03:29:45 INFO - 'SHELL': '/bin/bash', 03:29:45 INFO - 'SHLVL': '1', 03:29:45 INFO - 'TERM': 'linux', 03:29:45 INFO - 'TMOUT': '86400', 03:29:45 INFO - 'USER': 'cltbld', 03:29:45 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:45 INFO - '_': '/tools/buildbot/bin/python'} 03:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:45 INFO - Running setup.py (path:/tmp/pip-YEXZGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:45 INFO - Running setup.py (path:/tmp/pip-ZfICCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:45 INFO - Running setup.py (path:/tmp/pip-UarTm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:45 INFO - Running setup.py (path:/tmp/pip-xDRuPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:45 INFO - Running setup.py (path:/tmp/pip-CQpCXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:45 INFO - Running setup.py (path:/tmp/pip-1Mm2rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:45 INFO - Running setup.py (path:/tmp/pip-brWnqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:45 INFO - Running setup.py (path:/tmp/pip-arkSSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:46 INFO - Running setup.py (path:/tmp/pip-rcrvWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:46 INFO - Running setup.py (path:/tmp/pip-n8mz3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:46 INFO - Running setup.py (path:/tmp/pip-WpUy6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:46 INFO - Running setup.py (path:/tmp/pip-XWNcDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:46 INFO - Running setup.py (path:/tmp/pip-JG_LYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:46 INFO - Running setup.py (path:/tmp/pip-9K_sfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:46 INFO - Running setup.py (path:/tmp/pip-DYCSUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:46 INFO - Running setup.py (path:/tmp/pip-zy6OlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:46 INFO - Running setup.py (path:/tmp/pip-D33YMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29:48 INFO - Downloading blessings-1.6.tar.gz 03:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:29:48 INFO - Installing collected packages: blessings 03:29:48 INFO - Running setup.py install for blessings 03:29:48 INFO - Successfully installed blessings 03:29:48 INFO - Cleaning up... 03:29:49 INFO - Return code: 0 03:29:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:29:49 INFO - Reading from file tmpfile_stdout 03:29:49 INFO - Current package versions: 03:29:49 INFO - argparse == 1.2.1 03:29:49 INFO - blessings == 1.6 03:29:49 INFO - blobuploader == 1.2.4 03:29:49 INFO - docopt == 0.6.1 03:29:49 INFO - functools32 == 3.2.3-2 03:29:49 INFO - jsonschema == 2.5.1 03:29:49 INFO - manifestparser == 1.1 03:29:49 INFO - mozInstall == 1.12 03:29:49 INFO - mozcrash == 0.17 03:29:49 INFO - mozdebug == 0.1 03:29:49 INFO - mozdevice == 0.48 03:29:49 INFO - mozfile == 1.2 03:29:49 INFO - mozhttpd == 0.7 03:29:49 INFO - mozinfo == 0.9 03:29:49 INFO - mozleak == 0.1 03:29:49 INFO - mozlog == 3.2 03:29:49 INFO - moznetwork == 0.27 03:29:49 INFO - mozprocess == 0.22 03:29:49 INFO - mozprofile == 0.28 03:29:49 INFO - mozrunner == 6.11 03:29:49 INFO - mozscreenshot == 0.1 03:29:49 INFO - mozsystemmonitor == 0.3 03:29:49 INFO - moztest == 0.7 03:29:49 INFO - mozversion == 1.4 03:29:49 INFO - psutil == 3.1.1 03:29:49 INFO - requests == 1.2.3 03:29:49 INFO - wsgiref == 0.1.2 03:29:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:49 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 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:29:49 INFO - Using env: {'DISPLAY': ':0', 03:29:49 INFO - 'HOME': '/home/cltbld', 03:29:49 INFO - 'LANG': 'en_US.UTF-8', 03:29:49 INFO - 'LANGUAGE': 'en_US:en', 03:29:49 INFO - 'LOGNAME': 'cltbld', 03:29:49 INFO - 'MAIL': '/var/mail/cltbld', 03:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:49 INFO - 'MOZ_NO_REMOTE': '1', 03:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:49 INFO - 'NO_EM_RESTART': '1', 03:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:49 INFO - 'PWD': '/builds/slave/test', 03:29:49 INFO - 'SHELL': '/bin/bash', 03:29:49 INFO - 'SHLVL': '1', 03:29:49 INFO - 'TERM': 'linux', 03:29:49 INFO - 'TMOUT': '86400', 03:29:49 INFO - 'USER': 'cltbld', 03:29:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:49 INFO - '_': '/tools/buildbot/bin/python'} 03:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:29:49 INFO - Cleaning up... 03:29:49 INFO - Return code: 0 03:29:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29: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', 'jsonschema'] in /builds/slave/test/build 03:29: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 jsonschema 03:29:49 INFO - Using env: {'DISPLAY': ':0', 03:29:49 INFO - 'HOME': '/home/cltbld', 03:29:49 INFO - 'LANG': 'en_US.UTF-8', 03:29:49 INFO - 'LANGUAGE': 'en_US:en', 03:29:49 INFO - 'LOGNAME': 'cltbld', 03:29:49 INFO - 'MAIL': '/var/mail/cltbld', 03:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:49 INFO - 'MOZ_NO_REMOTE': '1', 03:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:49 INFO - 'NO_EM_RESTART': '1', 03:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:49 INFO - 'PWD': '/builds/slave/test', 03:29:49 INFO - 'SHELL': '/bin/bash', 03:29:49 INFO - 'SHLVL': '1', 03:29:49 INFO - 'TERM': 'linux', 03:29:49 INFO - 'TMOUT': '86400', 03:29:49 INFO - 'USER': 'cltbld', 03:29:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:49 INFO - '_': '/tools/buildbot/bin/python'} 03:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:29:49 INFO - Cleaning up... 03:29:49 INFO - Return code: 0 03:29:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95390af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, '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': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29: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', 'flake8'] in /builds/slave/test/build 03:29: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 flake8 03:29:49 INFO - Using env: {'DISPLAY': ':0', 03:29:49 INFO - 'HOME': '/home/cltbld', 03:29:49 INFO - 'LANG': 'en_US.UTF-8', 03:29:49 INFO - 'LANGUAGE': 'en_US:en', 03:29:49 INFO - 'LOGNAME': 'cltbld', 03:29:49 INFO - 'MAIL': '/var/mail/cltbld', 03:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:49 INFO - 'MOZ_NO_REMOTE': '1', 03:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:49 INFO - 'NO_EM_RESTART': '1', 03:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:49 INFO - 'PWD': '/builds/slave/test', 03:29:49 INFO - 'SHELL': '/bin/bash', 03:29:49 INFO - 'SHLVL': '1', 03:29:49 INFO - 'TERM': 'linux', 03:29:49 INFO - 'TMOUT': '86400', 03:29:49 INFO - 'USER': 'cltbld', 03:29:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:49 INFO - '_': '/tools/buildbot/bin/python'} 03:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:50 INFO - Downloading/unpacking flake8 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:29:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:29:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:29:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:52 INFO - no previously-included directories found matching 'docs/_build' 03:29:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:29:52 INFO - Running setup.py install for pep8 03:29:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:52 INFO - no previously-included directories found matching 'docs/_build' 03:29:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:29:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:29:52 INFO - Cleaning up... 03:29:52 INFO - Return code: 0 03:29:52 INFO - Running post-action listener: _resource_record_post_action 03:29:52 INFO - Running post-action listener: _start_resource_monitoring 03:29:52 INFO - Starting resource monitoring. 03:29:52 INFO - ##### 03:29:52 INFO - ##### Finished create-virtualenv step (success) 03:29:52 INFO - ##### 03:29:52 INFO - ##### 03:29:52 INFO - ##### Running install step. 03:29:52 INFO - ##### 03:29:52 INFO - Running pre-action listener: _resource_record_pre_action 03:29:52 INFO - Running main action method: install 03:29:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:29:53 INFO - Reading from file tmpfile_stdout 03:29:53 INFO - Detecting whether we're running mozinstall >=1.0... 03:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:29:53 INFO - Reading from file tmpfile_stdout 03:29:53 INFO - Output received: 03:29:53 INFO - Usage: mozinstall [options] installer 03:29:53 INFO - Options: 03:29:53 INFO - -h, --help show this help message and exit 03:29:53 INFO - -d DEST, --destination=DEST 03:29:53 INFO - Directory to install application into. [default: 03:29:53 INFO - "/builds/slave/test"] 03:29:53 INFO - --app=APP Application being installed. [default: firefox] 03:29:53 INFO - mkdir: /builds/slave/test/build/application 03:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:30:09 INFO - Reading from file tmpfile_stdout 03:30:09 INFO - Output received: 03:30:09 INFO - /builds/slave/test/build/application/firefox/firefox 03:30:09 INFO - Running post-action listener: _resource_record_post_action 03:30:09 INFO - ##### 03:30:09 INFO - ##### Finished install step (success) 03:30:09 INFO - ##### 03:30:09 INFO - ##### 03:30:09 INFO - ##### Running run-tests step. 03:30:09 INFO - ##### 03:30:09 INFO - Running pre-action listener: _resource_record_pre_action 03:30:09 INFO - Running main action method: run_tests 03:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:09 INFO - Python 2.7.3 03:30:09 INFO - Return code: 0 03:30:09 INFO - grabbing minidump binary from tooltool 03:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:09 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 0x24ba2c0>, '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 0x24bb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bb650>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:30:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:30:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:30:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:09 INFO - Return code: 0 03:30:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:30:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:30:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:30:10 INFO - Return code: 0 03:30:10 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/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:30:10 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/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:30:10 INFO - Using env: {'DISPLAY': ':0', 03:30:10 INFO - 'HOME': '/home/cltbld', 03:30:10 INFO - 'LANG': 'en_US.UTF-8', 03:30:10 INFO - 'LANGUAGE': 'en_US:en', 03:30:10 INFO - 'LOGNAME': 'cltbld', 03:30:10 INFO - 'MAIL': '/var/mail/cltbld', 03:30:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:10 INFO - 'MOZ_NO_REMOTE': '1', 03:30:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:10 INFO - 'NO_EM_RESTART': '1', 03:30:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:10 INFO - 'PWD': '/builds/slave/test', 03:30:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:10 INFO - 'SHELL': '/bin/bash', 03:30:10 INFO - 'SHLVL': '1', 03:30:10 INFO - 'TERM': 'linux', 03:30:10 INFO - 'TMOUT': '86400', 03:30:10 INFO - 'USER': 'cltbld', 03:30:10 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465973848.510247-25863408', 03:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:10 INFO - '_': '/tools/buildbot/bin/python'} 03:30:10 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/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:30:10 INFO - mozversion application_buildid: 20160615004406 03:30:10 INFO - mozversion application_changeset: 1a585c67e88e31e89aa165f6b706600a2ef92386 03:30:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:30:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:10 INFO - mozversion application_name: Firefox 03:30:10 INFO - mozversion application_remotingname: firefox-dev 03:30:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:30:10 INFO - mozversion application_vendor: Mozilla 03:30:10 INFO - mozversion application_version: 49.0a2 03:30:10 INFO - mozversion platform_buildid: 20160615004406 03:30:10 INFO - mozversion platform_changeset: 1a585c67e88e31e89aa165f6b706600a2ef92386 03:30:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:30:10 INFO - mozversion platform_version: 49.0a2 03:30:10 INFO - using testdate: 1465986610 03:30:10 INFO - actual date: 1465986610 03:30:10 INFO - starting webserver on 'localhost:56628' 03:30:10 INFO - SUITE-START | Running 2 tests 03:30:10 INFO - TEST-START | dromaeo_css 03:30:10 INFO - operating with platform_type : linux_ 03:30:10 INFO - Initialising browser for dromaeo_css test... 03:30:10 INFO - TEST-INFO | started process 2447 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXcVLPd/profile http://localhost:56628/getInfo.html) 03:30:14 INFO - PROCESS | 2447 | __metrics Screen width/height:1600/1200 03:30:14 INFO - PROCESS | 2447 | colorDepth:24 03:30:14 INFO - PROCESS | 2447 | Browser inner width/height: 1024/697 03:30:14 INFO - PROCESS | 2447 | __metrics 03:30:15 ERROR - PROCESS | 2447 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:30:15 INFO - PROCESS | 2447 | console.error: PushService: 03:30:15 INFO - PROCESS | 2447 | stateChangeProcessEnqueue: Error transitioning state 03:30:15 INFO - PROCESS | 2447 | UnknownError 03:30:16 INFO - TEST-INFO | 2447: exit 0 03:30:16 INFO - Browser initialized. 03:30:16 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:16 INFO - TEST-INFO | started process 2554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXcVLPd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:28 INFO - PROCESS | 2554 | RSS: Main: 145379328 03:30:28 INFO - PROCESS | 2554 | 03:31:19 INFO - PROCESS | 2554 | 1465986679626 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:33:27 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56628/tests/dromaeo/cssquery-ext.html) 03:33:28 INFO - PROCESS | 2554 | RSS: Main: 253894656 03:33:28 INFO - PROCESS | 2554 | 03:36:31 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-ext.html (next: http://localhost:56628/tests/dromaeo/cssquery-jquery.html) 03:36:31 INFO - PROCESS | 2554 | RSS: Main: 235122688 03:36:31 INFO - PROCESS | 2554 | 03:39:33 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56628/tests/dromaeo/cssquery-mootools.html) 03:39:34 INFO - PROCESS | 2554 | RSS: Main: 229883904 03:39:34 INFO - PROCESS | 2554 | 03:42:36 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56628/tests/dromaeo/cssquery-prototype.html) 03:42:36 INFO - PROCESS | 2554 | RSS: Main: 220975104 03:42:36 INFO - PROCESS | 2554 | 03:45:39 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56628/tests/dromaeo/cssquery-yui.html) 03:45:39 INFO - PROCESS | 2554 | RSS: Main: 218513408 03:45:39 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56628/tests/dromaeo/cssquery-yui.html (next: http://localhost:56628/tests/dromaeo/cssquery-dojo.html) 03:48:42 INFO - PROCESS | 2554 | RSS: Main: 253456384 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | __start_tp_report 03:48:42 INFO - PROCESS | 2554 | _x_x_mozilla_page_load 03:48:42 INFO - PROCESS | 2554 | _x_x_mozilla_page_load_details 03:48:42 INFO - PROCESS | 2554 | |i|pagename|runs| 03:48:42 INFO - PROCESS | 2554 | |0;dojo.html;3344.3311337732453;3367.848446819892;3387.0685561470577;3407.625161232266;3434.1757978085266;5554.194641776945;5556.361018482876;5646.237757902682;5688.687122208282;5713.085906254995;5559.972200139004;5599.076152434844;5601.327840659126;5602.1596760485945;5602.943970560294;4943.4562198158355;5073.822416215433;5076.263941728447;5105.693658380491;5142.614303927202;4993.925091123636;5060.51925067118;5097.643164978453;5097.7196254206;5107.821226257081;5074.2895994560795;5093.465186155456;5104.617153713468;5106.821261255856;5142.922856157161;5204.895902081956;5209.609279304067;5215.58275337972;5217.921731174036;5234.790608375661;4976.2038073908;5010.0731364697285;5057;5112.130937740593;5118.539331460186;5437.102878025121;5450.04624190765;5455.318085239345;5462;5497.9828731684365;6012.939870601282;6027.819165425044;6030.035194368924;6039.516838652898;6053.364396738323;5982.46157845796;5995.820125396245;6018.21763170785;6040.365761595057;6094.603850749687;5646.491815736604;5668.036433806222;5695.1741997410145;5701.429857014306;5705.144228365778;6045.848853778691;6172.753089876444;6178.598391104564;6180.5673602848265;6186.505079593621;5812.854678633068;5835.037218858925;5846.29844418664;5954.10688396744;5959.195508606369;5977.760889564369;5980.13288073227;6005.159277701126;6009.819705408758;6015.879682406415;5861.208736820475;5969.164316995624;5979.073243647242;5979.312379076375;6000.129981152709;6024.909626355608;6075.2405949256345;6087.726052327655;6117.755289788448;6119.755209791648;4634.814607415733;4646.83736069236;4647.790849411785;4648.883777905579;4676.719396836201;4042.0703238255364;4047.0489434982755;4095.5290141634096;4138.420621113047;4138.565450627701;5780.855478613068;5786.045302525036;5793.710314484343;5795.768169273266;5819.912701309484;6015.428534289323;6032.6983650818165;6057.666828324484;6071.6660583667435;6105.084237364431;5632.662040277596;5753.079507278815;5768.480836724715;5775.913361299584;5782.45066718661;5225.947740522623;5238.842834714965;5241.711705856138;5251.343582052244;5253.894922101536;6312.116303717483;6312.274088479791;6319.210098737658;6319.6208227506495;6326.082718005863;5857.619254748428;5991.221141251609;5993.880122397615;6042;6062.211912451529;6224.86850452295;6248;6256.061590761422;6256.687165641791;6262.248530176408;5930.584859059824;5943.989521781221;5947.226860508279;5978.880422391441;5983.252093488314;5944;6035.969820150871;6059.454649081604;6088.4215999479975;6140.600860944024;5945.821625351246;5972.313183983897;5973.283206015306;5979.790707325223;5983.640981541122;5643.463870932255;5824.21373114641;5904.468597826216;5959.791407300723;5960.3443621202505;5702.37273899879;5753.568482363756;5764.798232061858;5800.187973683604;5800.361960184292;5908.763649453974;5916.7041647918295;5999.400059993965;6010.33886272501;6031.788887388921;5632.464915832989;5710.37185909558;5869.295684517885;5880.8235752927485;5903.114532820111 03:48:42 INFO - PROCESS | 2554 | |1;ext.html;602.2171177469289;645.3933302695466;645.5706954875009;661.8411581220508;662.3244290823359;33525.83237083814;45555.77222113889;46425;46427.30359044617;46509.30236046462;47849.28226076603;48243.276350854765;48266.75866620662;48417.273740893914;48531.514684853144;8518.957405212996;9078.591463384131;9261.259099272042;9264.44413335202;11051.447427628627;10731.946340268327;10879.238453308271;11221.270617409884;11227.943860280728;11242.988131068203;11197.16021298406;11278.830817537744;11291.435428228597;11292.830607540893;11295.548178072908;8494.490330580185;11032.44837758109;11178.832317515244;11271.549138034468;11278.661640150727;11031;11130.443477826077;11132.16508761838;11142.888571114267;11145.609903653414;44661.55338446639;45082.32376514355;45085.54914450846;45106.54893451056;45153;3713.294474049922;3810.942835857464;3849.634284742945;4097.303458412077;4486.708363956365;35420.468692969625;35464.64535354665;35507.28985420303;35508.644913550794;35509.28981420357;4717.457492388351;4793.6031984008;4798.640101992364;4812.181929072066;4830.782614782308;357412;361280;361434;363241;363390;39504.60495395009;68808.6559567199;69588.65205674041;69672.65163674149;69698;39349;41370.79314603468;41543.37684934728;41560.37659435111;41601.79199103985;16998.235079421454;17199.312027518758;17202.913985429994;17210.483685489457;17217.569560760832;12246.693832654255;14008.719825603432;14152.929235353757;14161.78757318641;14528;9429.858552121723;9441.339106262496;9480.715578532656;9521.23830093591;9560.187384072293;3626.4197728363333;3648.653377929146;3701.463287823304;3706.314331848617;3749.9250014999548;1100.9009189173014;1137.5677242647741;1179.6520026592175;1238.9301145736213;1245.8878700916962;34148.48777268343;34296.314073718386;34402;34450.310993780484;34506;1130.9434528273553;1163.0985985861025;1185.7747028064641;1241.900647948162;1297.0920355751011;16864.699878107815;17097.572560686083;17299.394521191698;17367.47897563075;17492.737608935877;539583;541691;545468;545713;547480;64160.35839641544;64665.67667161634;64697.353026470075;64700.35299646943;64776;1351.4796803230827;1408.4929425406845;1458.2854401343266;1495.3868913745312;1499.6175975126273;31006;35283.294334113685;35284.22100547536;35908.820455897556;35946;54602.72698636481;55233.44766552283;55243.44756552383;55314;55377.44622553723;411183;414710;414880;415065;415491;30961.380772383975;32143.839280803448;32146.839265803523;32182;32222.516662249614;50741;52276.73861630668;52859.20711189258;53122;53785;43876.62578966927;43929.78035109804;44071.77964110159;44133.77933110314;44381.33427998518;43209.13581728411;43702.34446483369;44354.55645443504;44390.77804610956;45052.549474506144;43307;43903.34144987764;44124;44897;44988.32517512306;38916.80541597274;38986.2202755949;39991.40012899867;40309.39535907022;40647.39028914509;28948.90751765831;29858.701412985592;30020.95017242883;30033.69966300309;30257.69742302549 03:48:42 INFO - PROCESS | 2554 | |2;jquery.html;431.9245079751419;435.08848961425804;439.20065480824894;448.9708168969017;449.8335615822148;18894.811051889494;20482.590348193025;20609;21229.787702122972;21240.89379553104;18684.439466816017;19303.227870885185;20065.899670501665;20172.59654806903;20193.899030504865;5670.716464176795;5848.1520179574145;5865.677387743673;5901.026330655437;5914.2311499505;5744.6840423776675;5770.826875193751;5797.594168408213;5821.3887541808135;5826.796062137826;5745.511631511326;5745.712714364286;5749.108888122348;5757.337906140785;5787.739551720161;5569.331680198382;5819.970900145472;5835.095560188178;5835.533157347423;5837.795677151322;5020.1214787412055;5221.843344699665;5251.947480525184;5256.159014557652;5258.316418865561;20565.280215192397;20608.381748547567;23367.53264934692;23434.414139646477;23513.88243058791;2733.139061195717;2802.929926751827;2821.463921854841;2862.997950717253;2991.41667374862;14368.425262989467;14897.7765333519;15003.774943375858;15099.32053057604;15307.617309567238;2036.2364113457454;2069.7826728193622;2102.4428526440506;2173.413178441812;2181.476445653048;72387;73574.63212683955;74672.62663686702;81181.18818811796;84386;11258.324500529994;11531.76936461266;11644.2431241969;11817.586384476674;11853.407329633485;16849.747253791204;16907;16974;16987.575310617085;17048.14759262032;7821.139674635786;8129.796755081052;8213.34293256538;8413.326933845277;8494.239372204058;7603.467757256939;7736.226377362332;7762.495437796525;7771.533707977426;7814.413918956101;5053.519915608012;5117.692938423706;5139.794408223704;5205.973970130125;5308.840734777963;2051.323063389078;2093.5498867743204;2128.2019242783954;2139.3261122746285;2156.687280344341;1001.8528784541659;1005.6631028605353;1017.730301470111;1045.8535804987307;1066.6053560182781;18745;18883.62232755337;19208.231670733014;19322.033898305028;19609;1021.9576032447011;1034.1623285139062;1058.4654749351682;1082.182951871332;1100.257326304757;10487.265891387682;10784.08335291509;10929.836052459219;10960.342379457257;11088.112950963903;493445;511020;513615;518940;525909;88440.55779721189;92166;93479;93572;94284;1059.9841002384971;1060.2154405739698;1061.2147011211803;1065.6909496246158;1069.7379142110078;17255.654886902445;17451.738223926906;17669.734953975956;17673.734893976343;18081.18634661417;41352.79323603363;42120.578794211666;42418.78790606027;43251.78374108235;43880.78059609682;59109;59557.702211488664;60338.09492857523;60351.39648603458;61960;9202.125798049176;9302.209312208539;9310.906890931004;9366.250699943852;9514.191293739832;12359.814602781145;13584.32078396096;13685.11046781956;13810.723785524033;13900.72198556003;11769.823452648388;11799.882001180222;11804.940975295069;11975.520979160736;12118.818217726564;8876.535371663604;11726.589569365031;11866.644000679993;11876.228045177037;12007.939960300142;12024.158308918291;12041.939790301343;12145.514179432723;12292.323922184365;12312.445939932546;9200.447973121634;9300.534973251446;9319.813603728024;9344.558207070037;9370.67202647904;10897.509612067317;11882.584109556125;11900.464479098635;12061.396930153633;12108.273503589813 03:48:42 INFO - PROCESS | 2554 | |3;mootools.html;1437.942482300708;1438.2664840931122;1465.8754005909498;1558.8752899768017;1566.6005168681984;9454.763630909221;9757.51212439378;9809.705708828747;10060.144887684537;10177.287589868713;9741.902580974205;10059.497025148732;10097.596096156145;10104.14114800241;10107.494625268726;2486.080150344375;2514.8994040238367;2517.0686845866962;2517.8237523373373;2520.206135067448;2384.0582969727034;2421.140495124232;2422.8667423291713;2424.9030038798514;2428.0651948999675;2339.450229196146;2354.1760383865594;2404.627282771173;2406.2901444073955;2407.807375409972;2307.7807608277185;2366.715994080704;2368.97631023691;2380.452495925938;2386.033656369173;2227.610168220555;2286.27982185611;2287.1308902617066;2288.24487918988;2288.702468679061;8500.617472213766;9300;9863.704088877274;9876.35803672759;9926.106650401498;5783.479486846204;5930.584859059867;5933.7329820157765;5946.43508866657;5956.047032474827;3683.852645894161;3711.1464363196346;3713.164537978933;3733.5146430964073;3842.711796615265;1258.855231648363;1278.7314663920588;1293.760654278952;1302;1327.3429652322163;14143;14227.573172804832;14269.215193164371;14286.071405358722;14291;4925.13810083234;4988.127077761377;5047.722375269322;5051.2423136529815;5080.923786143211;10840.078593319657;10847.891521084846;10890.727731806768;10948.288361256493;10958.780824383468;5547.583931205176;5571.4150014248735;5578.386377498472;5590.273264475592;5600.635958662755;5117;5194.818181363634;5238.97380513095;5472.425395333512;5511;3995.360742281221;4031.9395209071886;4058.817353219112;4125.711200216017;4139.81370838313;1209.1898428053225;1212.121212121205;1225;1234.5617305856472;1238.5293588436336;973.0512750068626;1001.9499025048718;1004.3572113847143;1007.924405669563;1012.7518757904361;6056.4246396593135;6157.076438534255;6197.5351848611535;6277.435030847246;6315.526335524854;473.93127996440325;475.8382150068993;476.0835391870704;479.1829929969463;1032.1742605915238;4115.218108559364;4151.647109995684;4164.8125834337525;4177.143685544456;4177.895552611149;12225.877741222652;12483.812742808865;12531.87468125307;12572.748545029048;12658.367081646065;12159;12370.938145309216;12402.13185077054;12618.432170552163;12659.240445573296;9180.6786762463;9244.583993720298;9301.34890557668;9358.11097945694;9390.436573805593;6140.846478838065;6265.342139075331;6285.465735412358;6333.429991300805;6492.967535162294;11080.722981925193;11562.421878905852;11633.88366116328;11637.127215458706;11793.941030294794;11545.076393888638;11747.471363788822;11774.646760597196;11855.881441185478;11864.584739534075;8389.11914248995;8752.299816014576;8828.073052329413;8837.646494140163;8841.336899732418;9570.856437153308;9593.760155996157;9633.614655413985;9635.181009614262;9648.855267170858;8602.225799678059;8663.436876603;8729.905460927757;8831.116888311117;8877.11228877133;8252.21603947624;8361.874571881304;8400.075991640793;8537.87193192115;8555.957220213859;7202.171750248788;7227.927720722725;7252.637368131678;7255.310745479171;7265.963670181615;5016.724080175625;5206.739663016759;5208.921866171935;5324.653897496805;5366.543843773166;5123.436421993503;5138.12651849179;5168.508991645787;5308.893822123458;5314.681119132864 03:48:42 INFO - PROCESS | 2554 | |4;prototype.html;348.70708604771994;359.29936623584007;372.3558244237469;374.5692453678268;378.84656714030825;5490.203920431535;5514.36584792749;5519.31008623922;5549.916751248735;5551.528120109785;5299.443558426367;5425.240466334706;5430.728463576816;5466.207399927008;5469.206964990074;4294.806733696975;4324.870253892381;4330.177266319405;4345.261305578044;4363.738175709452;4340.480831708893;4377.781110944456;4381.9561804381865;4383.320585309266;4422.911541769163;4308.43990281263;4335.718178318415;4336.84821031264;4350.064736081742;4381.277089280281;4244.427002354673;4272.551382104881;4286.742795432284;4304.5910638489295;4352.107817897323;3845.8653947111998;3866.8839934802;3867.149227170018;3880.262750077476;3882.5340959084806;5273.31446911899;5314.601404894648;5321.3348331458565;5389.541888939445;5393.379761327419;4909.373485185409;5011.398632164127;5012.423571289312;5020.874478138039;5039.319691841628;5708.828735137952;5712.600117991742;5715.714214289269;5803.245578074865;5826.1552074948895;4262.46719160105;4280;4303.569643035671;4319.33050377192;4322.935155972663;5717;5729.369769325371;5756.740946657453;6023.8192854214;6085.574009819315;4271.572842715704;4281.636060934793;4283.850065247701;4298.075913678574;4305.6986010978935;4081.3673880548563;4342.131573685276;4358.8910277242685;4366.759828209479;4377.934330985037;4819;4865.318855360252;4871.756412179377;4874.902501949941;4895.902081958341;5762.798302059407;5764.538836893039;5789.247397838254;5793.117236898025;5811.4479124483105;1479.3564799312333;1486.6134804950575;1495.6859059597607;1497.5657059452853;1509.411329581464;945.7871978804714;948.8007518421209;962.9085236902483;976.023976023976;986.8865080515689;5003.724795136302;5015.623828212899;5015.899682006412;5022.5685679581875;5032.622553308516;5252.29094072303;5252.711100889412;5296.675831042239;5320.680759154463;5334.306540149833;4793.400824896888;4973.303737476756;4976.676516026447;5025.095482813055;5078.1210951948015;2851.5580085086845;2855.457463082008;2865.785066120049;2869.7130286971137;2869.8708558114936;5334.013207556614;5458.181272809111;5464.480874316933;5493.725313734298;5497.086544131616;5045.697258164522;5200.739963001834;5324.707141107198;5359.151092872952;5422.91865622016;5341.946580534222;5357.517823395913;5361.731913404236;5415.48552887475;5433.804562996134;5231.384307846077;5249.291345668438;5267.71027593486;5272.813616936159;5274.551663108678;5433.891322173614;5640.097584386478;5759.2512973313205;5845;5911.433470130505;6066.18106555627;6127.938720612915;6131.96934015327;6139.662318572521;6141.907871382022;4221.2190744712325;4239.724417912826;4239.766812825324;4250.36244563318;4250.787460626894;5749.281339832521;5931.881362372642;5959.612625179349;5959.76160953557;5977.910331344946;5744.712764361681;5789.971050144891;5806.216160818404;5810.244668193108;5827.1841942127285;5533.667979921218;5745.2818397700285;5753.021986262262;5798.507126894261;5817.70911454434;5716.971415142898;5730.255066841451;5733.913991290217;5742.827715168552;5753.165790960371;5835.183074369676;5842.591018628656;5852.678102704393;5868.413158684098;5889.646621202742;5504.201890725903;5690.2887139107615;5733.942660573341;5741.62679425836;5771.7114144293455 03:48:42 INFO - PROCESS | 2554 | |5;yui.html;756.776750858497;788.2826627768732;852.3011130872687;876.0801158783278;894.8389289927812;15935.442259520905;17041.48875533733;17465.91267043666;17480.562985925328;17508.124593770324;15598.610034749137;15661.451849185281;15666.764998525032;15697.764533531978;15936.362545498196;850.098895171119;869.9739007829744;872.720729366603;875.8248350329927;876.1019954546578;871.6644092024553;872.7861673889906;872.9519876406795;874.9387542872001;875.120503893586;916.3127654259306;917.2753524715466;917.5733284022928;920.5535315372039;920.742192186189;905.5110240470138;908.4594666173616;910.0671811393308;910.1671970147307;910.3263584947158;667.933206679333;668.214847554122;668.3516988521127;669.3206395508563;669.4778073102988;15089.54731358061;15203.239838008056;15224.695506089927;15262.847371526253;15278.923605382013;14362;14398.856011439857;14701.191434471206;15054.698906021818;15095.622609434851;14064;14202.715945681028;14284.857151428456;14416.135031898015;14680.04579702316;4768.451628062748;4847.054824309261;4856.490068542787;4905.632077594195;4967.10592093423;46335.30497042547;73736;74179.62910185469;77291;83106.99999999939;11609.59366422188;12572.371381431074;12594.81107783384;12743.235405875677;12793.68015799594;22350.776492235196;22366.552668946857;22413.66379504309;22434.551308974056;22438.551228975655;9307.348485605942;9364.812703745887;9415.90584094164;9579.856302155473;9738.610455581838;4964.900701986012;5028.421731500851;5041.269015992661;5133.974330128325;5166.405863325691;3798.9620103798607;3856.0552664597353;3863.903402414906;3908.90227744303;3909.843606255775;672;689.4243306838846;700.023467263165;706.7385067524946;720.4308596208937;710.399712243164;724.1961422820665;764.6635480388611;777.149021821105;823.9340852731767;10923.890761092287;11036.172287078502;11345.829812552818;11437.565582371373;11502.194846360842;804.4851295171095;830.3025458614793;830.4187069051688;835.9874601880977;838.1074156023936;5142.460041695643;5150.4334523202415;5206.245094461282;5219.817306394258;5316.840494785162;74485.6275718618;75852.62073689596;75896.62051689706;76528;123503.38248308701;113000.43499782449;113397.43301283606;114641;114909.4254528722;115561;79206.20793792103;80711.59644201741;81306.59346703347;89909;98646;10393.740156496147;10538.841917371092;10544.26190166681;10690.144788417063;11021.44892755343;52416;52797.208041878635;54153;54493.45506545043;54561.1815822755;52633.7368313156;76214;77043.61478192573;78329;78935;13634.250116243305;13715.794263086262;13929.094608850392;14018.439262429389;14295.92852035733;15844.76232856485;15966;16647.25087371047;16703.081330526937;17177;14643.853561464675;14677.706445871236;15121.470748523749;15730.528084157493;15731.842681573495;14071.366788494337;14816.185109818633;15018.048381628752;15192.392304307705;15352.07887526752;14257.928710356382;14928.552143435714;15096.773548397003;15243.847561524242;15390.69218615599;12347.938260308641;12534.749305014031;12675.17611355259;13050.738985220432;13165.144265623087;11673.35796531165;11815.763684726086;12096.153269271419;12141.150119491904;12144.710519539796 03:48:42 INFO - PROCESS | 2554 | __end_tp_report 03:48:42 INFO - PROCESS | 2554 | __start_cc_report 03:48:42 INFO - PROCESS | 2554 | _x_x_mozilla_cycle_collect,896 03:48:42 INFO - PROCESS | 2554 | __end_cc_report 03:48:42 INFO - PROCESS | 2554 | __startTimestamp1465987722606__endTimestamp 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | ------- Summary: start ------- 03:48:42 INFO - PROCESS | 2554 | Number of tests: 6 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#0] dojo.html Cycles:165 Average:5603.64 Median:5812.85 stddev:627.67 (10.8%) stddev-sans-first:604.05 03:48:42 INFO - PROCESS | 2554 | Values: 3344.3 3367.8 3387.1 3407.6 3434.2 5554.2 5556.4 5646.2 5688.7 5713.1 5560.0 5599.1 5601.3 5602.2 5602.9 4943.5 5073.8 5076.3 5105.7 5142.6 4993.9 5060.5 5097.6 5097.7 5107.8 5074.3 5093.5 5104.6 5106.8 5142.9 5204.9 5209.6 5215.6 5217.9 5234.8 4976.2 5010.1 5057.0 5112.1 5118.5 5437.1 5450.0 5455.3 5462.0 5498.0 6012.9 6027.8 6030.0 6039.5 6053.4 5982.5 5995.8 6018.2 6040.4 6094.6 5646.5 5668.0 5695.2 5701.4 5705.1 6045.8 6172.8 6178.6 6180.6 6186.5 5812.9 5835.0 5846.3 5954.1 5959.2 5977.8 5980.1 6005.2 6009.8 6015.9 5861.2 5969.2 5979.1 5979.3 6000.1 6024.9 6075.2 6087.7 6117.8 6119.8 4634.8 4646.8 4647.8 4648.9 4676.7 4042.1 4047.0 4095.5 4138.4 4138.6 5780.9 5786.0 5793.7 5795.8 5819.9 6015.4 6032.7 6057.7 6071.7 6105.1 5632.7 5753.1 5768.5 5775.9 5782.5 5225.9 5238.8 5241.7 5251.3 5253.9 6312.1 6312.3 6319.2 6319.6 6326.1 5857.6 5991.2 5993.9 6042.0 6062.2 6224.9 6248.0 6256.1 6256.7 6262.2 5930.6 5944.0 5947.2 5978.9 5983.3 5944.0 6036.0 6059.5 6088.4 6140.6 5945.8 5972.3 5973.3 5979.8 5983.6 5643.5 5824.2 5904.5 5959.8 5960.3 5702.4 5753.6 5764.8 5800.2 5800.4 5908.8 5916.7 5999.4 6010.3 6031.8 5632.5 5710.4 5869.3 5880.8 5903.1 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#1] ext.html Cycles:180 Average:61114.20 Median:39167.61 stddev:118214.61 (301.8%) stddev-sans-first:118458.93 03:48:42 INFO - PROCESS | 2554 | Values: 602.2 645.4 645.6 661.8 662.3 33525.8 45555.8 46425.0 46427.3 46509.3 47849.3 48243.3 48266.8 48417.3 48531.5 8519.0 9078.6 9261.3 9264.4 11051.4 10731.9 10879.2 11221.3 11227.9 11243.0 11197.2 11278.8 11291.4 11292.8 11295.5 8494.5 11032.4 11178.8 11271.5 11278.7 11031.0 11130.4 11132.2 11142.9 11145.6 44661.6 45082.3 45085.5 45106.5 45153.0 3713.3 3810.9 3849.6 4097.3 4486.7 35420.5 35464.6 35507.3 35508.6 35509.3 4717.5 4793.6 4798.6 4812.2 4830.8 357412.0 361280.0 361434.0 363241.0 363390.0 39504.6 68808.7 69588.7 69672.7 69698.0 39349.0 41370.8 41543.4 41560.4 41601.8 16998.2 17199.3 17202.9 17210.5 17217.6 12246.7 14008.7 14152.9 14161.8 14528.0 9429.9 9441.3 9480.7 9521.2 9560.2 3626.4 3648.7 3701.5 3706.3 3749.9 1100.9 1137.6 1179.7 1238.9 1245.9 34148.5 34296.3 34402.0 34450.3 34506.0 1130.9 1163.1 1185.8 1241.9 1297.1 16864.7 17097.6 17299.4 17367.5 17492.7 539583.0 541691.0 545468.0 545713.0 547480.0 64160.4 64665.7 64697.4 64700.4 64776.0 1351.5 1408.5 1458.3 1495.4 1499.6 31006.0 35283.3 35284.2 35908.8 35946.0 54602.7 55233.4 55243.4 55314.0 55377.4 411183.0 414710.0 414880.0 415065.0 415491.0 30961.4 32143.8 32146.8 32182.0 32222.5 50741.0 52276.7 52859.2 53122.0 53785.0 43876.6 43929.8 44071.8 44133.8 44381.3 43209.1 43702.3 44354.6 44390.8 45052.5 43307.0 43903.3 44124.0 44897.0 44988.3 38916.8 38986.2 39991.4 40309.4 40647.4 28948.9 29858.7 30021.0 30033.7 30257.7 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#2] jquery.html Cycles:180 Average:29946.00 Median:12774.50 stddev:84323.10 (660.1%) stddev-sans-first:84530.53 03:48:42 INFO - PROCESS | 2554 | Values: 431.9 435.1 439.2 449.0 449.8 18894.8 20482.6 20609.0 21229.8 21240.9 18684.4 19303.2 20065.9 20172.6 20193.9 5670.7 5848.2 5865.7 5901.0 5914.2 5744.7 5770.8 5797.6 5821.4 5826.8 5745.5 5745.7 5749.1 5757.3 5787.7 5569.3 5820.0 5835.1 5835.5 5837.8 5020.1 5221.8 5251.9 5256.2 5258.3 20565.3 20608.4 23367.5 23434.4 23513.9 2733.1 2802.9 2821.5 2863.0 2991.4 14368.4 14897.8 15003.8 15099.3 15307.6 2036.2 2069.8 2102.4 2173.4 2181.5 72387.0 73574.6 74672.6 81181.2 84386.0 11258.3 11531.8 11644.2 11817.6 11853.4 16849.7 16907.0 16974.0 16987.6 17048.1 7821.1 8129.8 8213.3 8413.3 8494.2 7603.5 7736.2 7762.5 7771.5 7814.4 5053.5 5117.7 5139.8 5206.0 5308.8 2051.3 2093.5 2128.2 2139.3 2156.7 1001.9 1005.7 1017.7 1045.9 1066.6 18745.0 18883.6 19208.2 19322.0 19609.0 1022.0 1034.2 1058.5 1082.2 1100.3 10487.3 10784.1 10929.8 10960.3 11088.1 493445.0 511020.0 513615.0 518940.0 525909.0 88440.6 92166.0 93479.0 93572.0 94284.0 1060.0 1060.2 1061.2 1065.7 1069.7 17255.7 17451.7 17669.7 17673.7 18081.2 41352.8 42120.6 42418.8 43251.8 43880.8 59109.0 59557.7 60338.1 60351.4 61960.0 9202.1 9302.2 9310.9 9366.3 9514.2 12359.8 13584.3 13685.1 13810.7 13900.7 11769.8 11799.9 11804.9 11975.5 12118.8 8876.5 11726.6 11866.6 11876.2 12007.9 12024.2 12041.9 12145.5 12292.3 12312.4 9200.4 9300.5 9319.8 9344.6 9370.7 10897.5 11882.6 11900.5 12061.4 12108.3 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#3] mootools.html Cycles:180 Average:6362.50 Median:4132.76 stddev:3829.43 (92.7%) stddev-sans-first:3822.29 03:48:42 INFO - PROCESS | 2554 | Values: 1437.9 1438.3 1465.9 1558.9 1566.6 9454.8 9757.5 9809.7 10060.1 10177.3 9741.9 10059.5 10097.6 10104.1 10107.5 2486.1 2514.9 2517.1 2517.8 2520.2 2384.1 2421.1 2422.9 2424.9 2428.1 2339.5 2354.2 2404.6 2406.3 2407.8 2307.8 2366.7 2369.0 2380.5 2386.0 2227.6 2286.3 2287.1 2288.2 2288.7 8500.6 9300.0 9863.7 9876.4 9926.1 5783.5 5930.6 5933.7 5946.4 5956.0 3683.9 3711.1 3713.2 3733.5 3842.7 1258.9 1278.7 1293.8 1302.0 1327.3 14143.0 14227.6 14269.2 14286.1 14291.0 4925.1 4988.1 5047.7 5051.2 5080.9 10840.1 10847.9 10890.7 10948.3 10958.8 5547.6 5571.4 5578.4 5590.3 5600.6 5117.0 5194.8 5239.0 5472.4 5511.0 3995.4 4031.9 4058.8 4125.7 4139.8 1209.2 1212.1 1225.0 1234.6 1238.5 973.1 1001.9 1004.4 1007.9 1012.8 6056.4 6157.1 6197.5 6277.4 6315.5 473.9 475.8 476.1 479.2 1032.2 4115.2 4151.6 4164.8 4177.1 4177.9 12225.9 12483.8 12531.9 12572.7 12658.4 12159.0 12370.9 12402.1 12618.4 12659.2 9180.7 9244.6 9301.3 9358.1 9390.4 6140.8 6265.3 6285.5 6333.4 6493.0 11080.7 11562.4 11633.9 11637.1 11793.9 11545.1 11747.5 11774.6 11855.9 11864.6 8389.1 8752.3 8828.1 8837.6 8841.3 9570.9 9593.8 9633.6 9635.2 9648.9 8602.2 8663.4 8729.9 8831.1 8877.1 8252.2 8361.9 8400.1 8537.9 8556.0 7202.2 7227.9 7252.6 7255.3 7266.0 5016.7 5206.7 5208.9 5324.7 5366.5 5123.4 5138.1 5168.5 5308.9 5314.7 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#4] prototype.html Cycles:180 Average:4743.96 Median:5298.06 stddev:1359.00 (25.7%) stddev-sans-first:1322.16 03:48:42 INFO - PROCESS | 2554 | Values: 348.7 359.3 372.4 374.6 378.8 5490.2 5514.4 5519.3 5549.9 5551.5 5299.4 5425.2 5430.7 5466.2 5469.2 4294.8 4324.9 4330.2 4345.3 4363.7 4340.5 4377.8 4382.0 4383.3 4422.9 4308.4 4335.7 4336.8 4350.1 4381.3 4244.4 4272.6 4286.7 4304.6 4352.1 3845.9 3866.9 3867.1 3880.3 3882.5 5273.3 5314.6 5321.3 5389.5 5393.4 4909.4 5011.4 5012.4 5020.9 5039.3 5708.8 5712.6 5715.7 5803.2 5826.2 4262.5 4280.0 4303.6 4319.3 4322.9 5717.0 5729.4 5756.7 6023.8 6085.6 4271.6 4281.6 4283.9 4298.1 4305.7 4081.4 4342.1 4358.9 4366.8 4377.9 4819.0 4865.3 4871.8 4874.9 4895.9 5762.8 5764.5 5789.2 5793.1 5811.4 1479.4 1486.6 1495.7 1497.6 1509.4 945.8 948.8 962.9 976.0 986.9 5003.7 5015.6 5015.9 5022.6 5032.6 5252.3 5252.7 5296.7 5320.7 5334.3 4793.4 4973.3 4976.7 5025.1 5078.1 2851.6 2855.5 2865.8 2869.7 2869.9 5334.0 5458.2 5464.5 5493.7 5497.1 5045.7 5200.7 5324.7 5359.2 5422.9 5341.9 5357.5 5361.7 5415.5 5433.8 5231.4 5249.3 5267.7 5272.8 5274.6 5433.9 5640.1 5759.3 5845.0 5911.4 6066.2 6127.9 6132.0 6139.7 6141.9 4221.2 4239.7 4239.8 4250.4 4250.8 5749.3 5931.9 5959.6 5959.8 5977.9 5744.7 5790.0 5806.2 5810.2 5827.2 5533.7 5745.3 5753.0 5798.5 5817.7 5717.0 5730.3 5733.9 5742.8 5753.2 5835.2 5842.6 5852.7 5868.4 5889.6 5504.2 5690.3 5733.9 5741.6 5771.7 03:48:42 INFO - PROCESS | 2554 | 03:48:42 INFO - PROCESS | 2554 | [#5] yui.html Cycles:180 Average:20905.23 Median:25122.57 stddev:28748.71 (114.4%) stddev-sans-first:28789.55 03:48:42 INFO - PROCESS | 2554 | Values: 756.8 788.3 852.3 876.1 894.8 15935.4 17041.5 17465.9 17480.6 17508.1 15598.6 15661.5 15666.8 15697.8 15936.4 850.1 870.0 872.7 875.8 876.1 871.7 872.8 873.0 874.9 875.1 916.3 917.3 917.6 920.6 920.7 905.5 908.5 910.1 910.2 910.3 667.9 668.2 668.4 669.3 669.5 15089.5 15203.2 15224.7 15262.8 15278.9 14362.0 14398.9 14701.2 15054.7 15095.6 14064.0 14202.7 14284.9 14416.1 14680.0 4768.5 4847.1 4856.5 4905.6 4967.1 46335.3 73736.0 74179.6 77291.0 83107.0 11609.6 12572.4 12594.8 12743.2 12793.7 22350.8 22366.6 22413.7 22434.6 22438.6 9307.3 9364.8 9415.9 9579.9 9738.6 4964.9 5028.4 5041.3 5134.0 5166.4 3799.0 3856.1 3863.9 3908.9 3909.8 672.0 689.4 700.0 706.7 720.4 710.4 724.2 764.7 777.1 823.9 10923.9 11036.2 11345.8 11437.6 11502.2 804.5 830.3 830.4 836.0 838.1 5142.5 5150.4 5206.2 5219.8 5316.8 74485.6 75852.6 75896.6 76528.0 123503.4 113000.4 113397.4 114641.0 114909.4 115561.0 79206.2 80711.6 81306.6 89909.0 98646.0 10393.7 10538.8 10544.3 10690.1 11021.4 52416.0 52797.2 54153.0 54493.5 54561.2 52633.7 76214.0 77043.6 78329.0 78935.0 13634.3 13715.8 13929.1 14018.4 14295.9 15844.8 15966.0 16647.3 16703.1 17177.0 14643.9 14677.7 15121.5 15730.5 15731.8 14071.4 14816.2 15018.0 15192.4 15352.1 14257.9 14928.6 15096.8 15243.8 15390.7 12347.9 12534.7 12675.2 13050.7 13165.1 11673.4 11815.8 12096.2 12141.2 12144.7 03:48:42 INFO - PROCESS | 2554 | -------- Summary: end -------- 03:48:42 INFO - PROCESS | 2554 | 03:48:43 INFO - TEST-INFO | 2554: exit 0 03:48:43 INFO - TEST-OK | dromaeo_css | took 1112951ms 03:48:43 INFO - TEST-START | kraken 03:48:43 INFO - operating with platform_type : linux_ 03:48:43 INFO - Initialising browser for kraken test... 03:48:43 INFO - TEST-INFO | started process 2820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFUcss/profile http://localhost:56628/getInfo.html) 03:48:47 INFO - PROCESS | 2820 | __metrics Screen width/height:1600/1200 03:48:47 INFO - PROCESS | 2820 | colorDepth:24 03:48:47 INFO - PROCESS | 2820 | Browser inner width/height: 1024/697 03:48:47 INFO - PROCESS | 2820 | __metrics 03:48:48 ERROR - PROCESS | 2820 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:48:48 INFO - PROCESS | 2820 | console.error: PushService: 03:48:48 INFO - PROCESS | 2820 | stateChangeProcessEnqueue: Error transitioning state 03:48:48 INFO - PROCESS | 2820 | UnknownError 03:48:49 INFO - TEST-INFO | 2820: exit 0 03:48:49 INFO - Browser initialized. 03:48:49 INFO - Running cycle 1/1 for kraken test... 03:48:49 INFO - TEST-INFO | started process 2945 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFUcss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:01 INFO - PROCESS | 2945 | RSS: Main: 145772544 03:49:01 INFO - PROCESS | 2945 | 03:49:03 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56628/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:04 INFO - PROCESS | 2945 | RSS: Main: 194551808 03:49:04 INFO - PROCESS | 2945 | 03:49:07 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56628/tests/kraken/driver.html?testName=audio-dft) 03:49:07 INFO - PROCESS | 2945 | RSS: Main: 192188416 03:49:07 INFO - PROCESS | 2945 | 03:49:10 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56628/tests/kraken/driver.html?testName=audio-fft) 03:49:11 INFO - PROCESS | 2945 | RSS: Main: 277565440 03:49:11 INFO - PROCESS | 2945 | 03:49:13 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56628/tests/kraken/driver.html?testName=audio-oscillator) 03:49:13 INFO - PROCESS | 2945 | RSS: Main: 186900480 03:49:13 INFO - PROCESS | 2945 | 03:49:15 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56628/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:16 INFO - PROCESS | 2945 | RSS: Main: 187305984 03:49:16 INFO - PROCESS | 2945 | 03:49:19 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56628/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:20 INFO - PROCESS | 2945 | RSS: Main: 223113216 03:49:20 INFO - PROCESS | 2945 | 03:49:23 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56628/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:24 INFO - PROCESS | 2945 | RSS: Main: 222629888 03:49:24 INFO - PROCESS | 2945 | 03:49:26 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56628/tests/kraken/driver.html?testName=json-parse-financial) 03:49:27 INFO - PROCESS | 2945 | RSS: Main: 221908992 03:49:27 INFO - PROCESS | 2945 | 03:49:28 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56628/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:29 INFO - PROCESS | 2945 | RSS: Main: 200667136 03:49:29 INFO - PROCESS | 2945 | 03:49:31 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:31 INFO - PROCESS | 2945 | RSS: Main: 201891840 03:49:31 INFO - PROCESS | 2945 | 03:49:33 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:34 INFO - PROCESS | 2945 | RSS: Main: 208969728 03:49:34 INFO - PROCESS | 2945 | 03:49:36 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:37 INFO - PROCESS | 2945 | RSS: Main: 213471232 03:49:37 INFO - PROCESS | 2945 | 03:49:40 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:40 INFO - PROCESS | 2945 | RSS: Main: 214986752 03:49:40 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | Cycle 1(1): loaded http://localhost:56628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56628/tests/kraken/driver.html?testName=ai-astar) 03:49:42 INFO - PROCESS | 2945 | RSS: Main: 257507328 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | __start_tp_report 03:49:42 INFO - PROCESS | 2945 | _x_x_mozilla_page_load 03:49:42 INFO - PROCESS | 2945 | _x_x_mozilla_page_load_details 03:49:42 INFO - PROCESS | 2945 | |i|pagename|runs| 03:49:42 INFO - PROCESS | 2945 | |0;ai-astar;111;103;110;110;110;110;108;109;109;110 03:49:42 INFO - PROCESS | 2945 | |1;audio-beat-detection;150;124;126;174;125;125;155;125;161;125 03:49:42 INFO - PROCESS | 2945 | |2;audio-dft;161;161;160;160;160;161;162;161;160;161 03:49:42 INFO - PROCESS | 2945 | |3;audio-fft;74;77;74;118;74;75;74;75;106;74 03:49:42 INFO - PROCESS | 2945 | |4;audio-oscillator;83;84;117;83;84;83;125;83;85;128 03:49:42 INFO - PROCESS | 2945 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;116;117 03:49:42 INFO - PROCESS | 2945 | |6;imaging-darkroom;146;145;146;146;148;145;145;146;148;146 03:49:42 INFO - PROCESS | 2945 | |7;imaging-desaturate;95;94;94;94;95;94;95;94;94;94 03:49:42 INFO - PROCESS | 2945 | |8;json-parse-financial;50;49;56;54;50;51;53;51;51;51 03:49:42 INFO - PROCESS | 2945 | |9;json-stringify-tinderbox;60;61;60;60;60;60;60;60;60;60 03:49:42 INFO - PROCESS | 2945 | |10;stanford-crypto-aes;78;76;76;77;76;78;77;76;108;77 03:49:42 INFO - PROCESS | 2945 | |11;stanford-crypto-ccm;129;121;118;121;119;116;129;132;130;120 03:49:42 INFO - PROCESS | 2945 | |12;stanford-crypto-pbkdf2;153;152;151;157;155;153;160;155;152;150 03:49:42 INFO - PROCESS | 2945 | |13;stanford-crypto-sha256-iterative;61;63;64;65;63;64;62;62;62;60 03:49:42 INFO - PROCESS | 2945 | __end_tp_report 03:49:42 INFO - PROCESS | 2945 | __start_cc_report 03:49:42 INFO - PROCESS | 2945 | _x_x_mozilla_cycle_collect,1595 03:49:42 INFO - PROCESS | 2945 | __end_cc_report 03:49:42 INFO - PROCESS | 2945 | __startTimestamp1465987782697__endTimestamp 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | ------- Summary: start ------- 03:49:42 INFO - PROCESS | 2945 | Number of tests: 14 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:2.26 (2.1%) stddev-sans-first:2.28 03:49:42 INFO - PROCESS | 2945 | Values: 111.0 103.0 110.0 110.0 110.0 110.0 108.0 109.0 109.0 110.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:138.00 stddev:19.04 (13.8%) stddev-sans-first:19.78 03:49:42 INFO - PROCESS | 2945 | Values: 150.0 124.0 126.0 174.0 125.0 125.0 155.0 125.0 161.0 125.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 03:49:42 INFO - PROCESS | 2945 | Values: 161.0 161.0 160.0 160.0 160.0 161.0 162.0 161.0 160.0 161.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#3] audio-fft Cycles:10 Average:82.10 Median:74.00 stddev:16.04 (21.7%) stddev-sans-first:16.74 03:49:42 INFO - PROCESS | 2945 | Values: 74.0 77.0 74.0 118.0 74.0 75.0 74.0 75.0 106.0 74.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:83.00 stddev:19.40 (23.4%) stddev-sans-first:20.05 03:49:42 INFO - PROCESS | 2945 | Values: 83.0 84.0 117.0 83.0 84.0 83.0 125.0 83.0 85.0 128.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:49:42 INFO - PROCESS | 2945 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:1.10 (0.8%) stddev-sans-first:1.17 03:49:42 INFO - PROCESS | 2945 | Values: 146.0 145.0 146.0 146.0 148.0 145.0 145.0 146.0 148.0 146.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 03:49:42 INFO - PROCESS | 2945 | Values: 95.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.00 stddev:2.12 (4.2%) stddev-sans-first:2.17 03:49:42 INFO - PROCESS | 2945 | Values: 50.0 49.0 56.0 54.0 50.0 51.0 53.0 51.0 51.0 51.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 03:49:42 INFO - PROCESS | 2945 | Values: 60.0 61.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:77.00 stddev:9.90 (12.9%) stddev-sans-first:10.48 03:49:42 INFO - PROCESS | 2945 | Values: 78.0 76.0 76.0 77.0 76.0 78.0 77.0 76.0 108.0 77.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:125.00 stddev:5.84 (4.7%) stddev-sans-first:5.84 03:49:42 INFO - PROCESS | 2945 | Values: 129.0 121.0 118.0 121.0 119.0 116.0 129.0 132.0 130.0 120.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:154.00 stddev:3.01 (2.0%) stddev-sans-first:3.18 03:49:42 INFO - PROCESS | 2945 | Values: 153.0 152.0 151.0 157.0 155.0 153.0 160.0 155.0 152.0 150.0 03:49:42 INFO - PROCESS | 2945 | 03:49:42 INFO - PROCESS | 2945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.51 (2.4%) stddev-sans-first:1.48 03:49:42 INFO - PROCESS | 2945 | Values: 61.0 63.0 64.0 65.0 63.0 64.0 62.0 62.0 62.0 60.0 03:49:42 INFO - PROCESS | 2945 | -------- Summary: end -------- 03:49:42 INFO - PROCESS | 2945 | 03:49:43 INFO - TEST-INFO | 2945: exit 0 03:49:43 INFO - TEST-OK | kraken | took 60419ms 03:49:43 INFO - SUITE-END | took 1173s 03:49:43 INFO - Completed test suite (00:19:33) 03:49:43 INFO - javascript benchmark 03:49:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.3311337732453, 3367.848446819892, 3387.0685561470577, 3407.625161232266, 3434.1757978085266, 5554.194641776945, 5556.361018482876, 5646.237757902682, 5688.687122208282, 5713.085906254995, 5559.972200139004, 5599.076152434844, 5601.327840659126, 5602.1596760485945, 5602.943970560294, 4943.4562198158355, 5073.822416215433, 5076.263941728447, 5105.693658380491, 5142.614303927202, 4993.925091123636, 5060.51925067118, 5097.643164978453, 5097.7196254206, 5107.821226257081, 5074.2895994560795, 5093.465186155456, 5104.617153713468, 5106.821261255856, 5142.922856157161, 5204.895902081956, 5209.609279304067, 5215.58275337972, 5217.921731174036, 5234.790608375661, 4976.2038073908, 5010.0731364697285, 5057.0, 5112.130937740593, 5118.539331460186, 5437.102878025121, 5450.04624190765, 5455.318085239345, 5462.0, 5497.9828731684365, 6012.939870601282, 6027.819165425044, 6030.035194368924, 6039.516838652898, 6053.364396738323, 5982.46157845796, 5995.820125396245, 6018.21763170785, 6040.365761595057, 6094.603850749687, 5646.491815736604, 5668.036433806222, 5695.1741997410145, 5701.429857014306, 5705.144228365778, 6045.848853778691, 6172.753089876444, 6178.598391104564, 6180.5673602848265, 6186.505079593621, 5812.854678633068, 5835.037218858925, 5846.29844418664, 5954.10688396744, 5959.195508606369, 5977.760889564369, 5980.13288073227, 6005.159277701126, 6009.819705408758, 6015.879682406415, 5861.208736820475, 5969.164316995624, 5979.073243647242, 5979.312379076375, 6000.129981152709, 6024.909626355608, 6075.2405949256345, 6087.726052327655, 6117.755289788448, 6119.755209791648, 4634.814607415733, 4646.83736069236, 4647.790849411785, 4648.883777905579, 4676.719396836201, 4042.0703238255364, 4047.0489434982755, 4095.5290141634096, 4138.420621113047, 4138.565450627701, 5780.855478613068, 5786.045302525036, 5793.710314484343, 5795.768169273266, 5819.912701309484, 6015.428534289323, 6032.6983650818165, 6057.666828324484, 6071.6660583667435, 6105.084237364431, 5632.662040277596, 5753.079507278815, 5768.480836724715, 5775.913361299584, 5782.45066718661, 5225.947740522623, 5238.842834714965, 5241.711705856138, 5251.343582052244, 5253.894922101536, 6312.116303717483, 6312.274088479791, 6319.210098737658, 6319.6208227506495, 6326.082718005863, 5857.619254748428, 5991.221141251609, 5993.880122397615, 6042.0, 6062.211912451529, 6224.86850452295, 6248.0, 6256.061590761422, 6256.687165641791, 6262.248530176408, 5930.584859059824, 5943.989521781221, 5947.226860508279, 5978.880422391441, 5983.252093488314, 5944.0, 6035.969820150871, 6059.454649081604, 6088.4215999479975, 6140.600860944024, 5945.821625351246, 5972.313183983897, 5973.283206015306, 5979.790707325223, 5983.640981541122, 5643.463870932255, 5824.21373114641, 5904.468597826216, 5959.791407300723, 5960.3443621202505, 5702.37273899879, 5753.568482363756, 5764.798232061858, 5800.187973683604, 5800.361960184292, 5908.763649453974, 5916.7041647918295, 5999.400059993965, 6010.33886272501, 6031.788887388921, 5632.464915832989, 5710.37185909558, 5869.295684517885, 5880.8235752927485, 5903.114532820111], "value": 5562.558569028923, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.2171177469289, 645.3933302695466, 645.5706954875009, 661.8411581220508, 662.3244290823359, 33525.83237083814, 45555.77222113889, 46425.0, 46427.30359044617, 46509.30236046462, 47849.28226076603, 48243.276350854765, 48266.75866620662, 48417.273740893914, 48531.514684853144, 8518.957405212996, 9078.591463384131, 9261.259099272042, 9264.44413335202, 11051.447427628627, 10731.946340268327, 10879.238453308271, 11221.270617409884, 11227.943860280728, 11242.988131068203, 11197.16021298406, 11278.830817537744, 11291.435428228597, 11292.830607540893, 11295.548178072908, 8494.490330580185, 11032.44837758109, 11178.832317515244, 11271.549138034468, 11278.661640150727, 11031.0, 11130.443477826077, 11132.16508761838, 11142.888571114267, 11145.609903653414, 44661.55338446639, 45082.32376514355, 45085.54914450846, 45106.54893451056, 45153.0, 3713.294474049922, 3810.942835857464, 3849.634284742945, 4097.303458412077, 4486.708363956365, 35420.468692969625, 35464.64535354665, 35507.28985420303, 35508.644913550794, 35509.28981420357, 4717.457492388351, 4793.6031984008, 4798.640101992364, 4812.181929072066, 4830.782614782308, 357412.0, 361280.0, 361434.0, 363241.0, 363390.0, 39504.60495395009, 68808.6559567199, 69588.65205674041, 69672.65163674149, 69698.0, 39349.0, 41370.79314603468, 41543.37684934728, 41560.37659435111, 41601.79199103985, 16998.235079421454, 17199.312027518758, 17202.913985429994, 17210.483685489457, 17217.569560760832, 12246.693832654255, 14008.719825603432, 14152.929235353757, 14161.78757318641, 14528.0, 9429.858552121723, 9441.339106262496, 9480.715578532656, 9521.23830093591, 9560.187384072293, 3626.4197728363333, 3648.653377929146, 3701.463287823304, 3706.314331848617, 3749.9250014999548, 1100.9009189173014, 1137.5677242647741, 1179.6520026592175, 1238.9301145736213, 1245.8878700916962, 34148.48777268343, 34296.314073718386, 34402.0, 34450.310993780484, 34506.0, 1130.9434528273553, 1163.0985985861025, 1185.7747028064641, 1241.900647948162, 1297.0920355751011, 16864.699878107815, 17097.572560686083, 17299.394521191698, 17367.47897563075, 17492.737608935877, 539583.0, 541691.0, 545468.0, 545713.0, 547480.0, 64160.35839641544, 64665.67667161634, 64697.353026470075, 64700.35299646943, 64776.0, 1351.4796803230827, 1408.4929425406845, 1458.2854401343266, 1495.3868913745312, 1499.6175975126273, 31006.0, 35283.294334113685, 35284.22100547536, 35908.820455897556, 35946.0, 54602.72698636481, 55233.44766552283, 55243.44756552383, 55314.0, 55377.44622553723, 411183.0, 414710.0, 414880.0, 415065.0, 415491.0, 30961.380772383975, 32143.839280803448, 32146.839265803523, 32182.0, 32222.516662249614, 50741.0, 52276.73861630668, 52859.20711189258, 53122.0, 53785.0, 43876.62578966927, 43929.78035109804, 44071.77964110159, 44133.77933110314, 44381.33427998518, 43209.13581728411, 43702.34446483369, 44354.55645443504, 44390.77804610956, 45052.549474506144, 43307.0, 43903.34144987764, 44124.0, 44897.0, 44988.32517512306, 38916.80541597274, 38986.2202755949, 39991.40012899867, 40309.39535907022, 40647.39028914509, 28948.90751765831, 29858.701412985592, 30020.95017242883, 30033.69966300309, 30257.69742302549], "value": 20758.10110459253, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.9245079751419, 435.08848961425804, 439.20065480824894, 448.9708168969017, 449.8335615822148, 18894.811051889494, 20482.590348193025, 20609.0, 21229.787702122972, 21240.89379553104, 18684.439466816017, 19303.227870885185, 20065.899670501665, 20172.59654806903, 20193.899030504865, 5670.716464176795, 5848.1520179574145, 5865.677387743673, 5901.026330655437, 5914.2311499505, 5744.6840423776675, 5770.826875193751, 5797.594168408213, 5821.3887541808135, 5826.796062137826, 5745.511631511326, 5745.712714364286, 5749.108888122348, 5757.337906140785, 5787.739551720161, 5569.331680198382, 5819.970900145472, 5835.095560188178, 5835.533157347423, 5837.795677151322, 5020.1214787412055, 5221.843344699665, 5251.947480525184, 5256.159014557652, 5258.316418865561, 20565.280215192397, 20608.381748547567, 23367.53264934692, 23434.414139646477, 23513.88243058791, 2733.139061195717, 2802.929926751827, 2821.463921854841, 2862.997950717253, 2991.41667374862, 14368.425262989467, 14897.7765333519, 15003.774943375858, 15099.32053057604, 15307.617309567238, 2036.2364113457454, 2069.7826728193622, 2102.4428526440506, 2173.413178441812, 2181.476445653048, 72387.0, 73574.63212683955, 74672.62663686702, 81181.18818811796, 84386.0, 11258.324500529994, 11531.76936461266, 11644.2431241969, 11817.586384476674, 11853.407329633485, 16849.747253791204, 16907.0, 16974.0, 16987.575310617085, 17048.14759262032, 7821.139674635786, 8129.796755081052, 8213.34293256538, 8413.326933845277, 8494.239372204058, 7603.467757256939, 7736.226377362332, 7762.495437796525, 7771.533707977426, 7814.413918956101, 5053.519915608012, 5117.692938423706, 5139.794408223704, 5205.973970130125, 5308.840734777963, 2051.323063389078, 2093.5498867743204, 2128.2019242783954, 2139.3261122746285, 2156.687280344341, 1001.8528784541659, 1005.6631028605353, 1017.730301470111, 1045.8535804987307, 1066.6053560182781, 18745.0, 18883.62232755337, 19208.231670733014, 19322.033898305028, 19609.0, 1021.9576032447011, 1034.1623285139062, 1058.4654749351682, 1082.182951871332, 1100.257326304757, 10487.265891387682, 10784.08335291509, 10929.836052459219, 10960.342379457257, 11088.112950963903, 493445.0, 511020.0, 513615.0, 518940.0, 525909.0, 88440.55779721189, 92166.0, 93479.0, 93572.0, 94284.0, 1059.9841002384971, 1060.2154405739698, 1061.2147011211803, 1065.6909496246158, 1069.7379142110078, 17255.654886902445, 17451.738223926906, 17669.734953975956, 17673.734893976343, 18081.18634661417, 41352.79323603363, 42120.578794211666, 42418.78790606027, 43251.78374108235, 43880.78059609682, 59109.0, 59557.702211488664, 60338.09492857523, 60351.39648603458, 61960.0, 9202.125798049176, 9302.209312208539, 9310.906890931004, 9366.250699943852, 9514.191293739832, 12359.814602781145, 13584.32078396096, 13685.11046781956, 13810.723785524033, 13900.72198556003, 11769.823452648388, 11799.882001180222, 11804.940975295069, 11975.520979160736, 12118.818217726564, 8876.535371663604, 11726.589569365031, 11866.644000679993, 11876.228045177037, 12007.939960300142, 12024.158308918291, 12041.939790301343, 12145.514179432723, 12292.323922184365, 12312.445939932546, 9200.447973121634, 9300.534973251446, 9319.813603728024, 9344.558207070037, 9370.67202647904, 10897.509612067317, 11882.584109556125, 11900.464479098635, 12061.396930153633, 12108.273503589813], "value": 9628.679058354022, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.942482300708, 1438.2664840931122, 1465.8754005909498, 1558.8752899768017, 1566.6005168681984, 9454.763630909221, 9757.51212439378, 9809.705708828747, 10060.144887684537, 10177.287589868713, 9741.902580974205, 10059.497025148732, 10097.596096156145, 10104.14114800241, 10107.494625268726, 2486.080150344375, 2514.8994040238367, 2517.0686845866962, 2517.8237523373373, 2520.206135067448, 2384.0582969727034, 2421.140495124232, 2422.8667423291713, 2424.9030038798514, 2428.0651948999675, 2339.450229196146, 2354.1760383865594, 2404.627282771173, 2406.2901444073955, 2407.807375409972, 2307.7807608277185, 2366.715994080704, 2368.97631023691, 2380.452495925938, 2386.033656369173, 2227.610168220555, 2286.27982185611, 2287.1308902617066, 2288.24487918988, 2288.702468679061, 8500.617472213766, 9300.0, 9863.704088877274, 9876.35803672759, 9926.106650401498, 5783.479486846204, 5930.584859059867, 5933.7329820157765, 5946.43508866657, 5956.047032474827, 3683.852645894161, 3711.1464363196346, 3713.164537978933, 3733.5146430964073, 3842.711796615265, 1258.855231648363, 1278.7314663920588, 1293.760654278952, 1302.0, 1327.3429652322163, 14143.0, 14227.573172804832, 14269.215193164371, 14286.071405358722, 14291.0, 4925.13810083234, 4988.127077761377, 5047.722375269322, 5051.2423136529815, 5080.923786143211, 10840.078593319657, 10847.891521084846, 10890.727731806768, 10948.288361256493, 10958.780824383468, 5547.583931205176, 5571.4150014248735, 5578.386377498472, 5590.273264475592, 5600.635958662755, 5117.0, 5194.818181363634, 5238.97380513095, 5472.425395333512, 5511.0, 3995.360742281221, 4031.9395209071886, 4058.817353219112, 4125.711200216017, 4139.81370838313, 1209.1898428053225, 1212.121212121205, 1225.0, 1234.5617305856472, 1238.5293588436336, 973.0512750068626, 1001.9499025048718, 1004.3572113847143, 1007.924405669563, 1012.7518757904361, 6056.4246396593135, 6157.076438534255, 6197.5351848611535, 6277.435030847246, 6315.526335524854, 473.93127996440325, 475.8382150068993, 476.0835391870704, 479.1829929969463, 1032.1742605915238, 4115.218108559364, 4151.647109995684, 4164.8125834337525, 4177.143685544456, 4177.895552611149, 12225.877741222652, 12483.812742808865, 12531.87468125307, 12572.748545029048, 12658.367081646065, 12159.0, 12370.938145309216, 12402.13185077054, 12618.432170552163, 12659.240445573296, 9180.6786762463, 9244.583993720298, 9301.34890557668, 9358.11097945694, 9390.436573805593, 6140.846478838065, 6265.342139075331, 6285.465735412358, 6333.429991300805, 6492.967535162294, 11080.722981925193, 11562.421878905852, 11633.88366116328, 11637.127215458706, 11793.941030294794, 11545.076393888638, 11747.471363788822, 11774.646760597196, 11855.881441185478, 11864.584739534075, 8389.11914248995, 8752.299816014576, 8828.073052329413, 8837.646494140163, 8841.336899732418, 9570.856437153308, 9593.760155996157, 9633.614655413985, 9635.181009614262, 9648.855267170858, 8602.225799678059, 8663.436876603, 8729.905460927757, 8831.116888311117, 8877.11228877133, 8252.21603947624, 8361.874571881304, 8400.075991640793, 8537.87193192115, 8555.957220213859, 7202.171750248788, 7227.927720722725, 7252.637368131678, 7255.310745479171, 7265.963670181615, 5016.724080175625, 5206.739663016759, 5208.921866171935, 5324.653897496805, 5366.543843773166, 5123.436421993503, 5138.12651849179, 5168.508991645787, 5308.893822123458, 5314.681119132864], "value": 4920.257492177817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.70708604771994, 359.29936623584007, 372.3558244237469, 374.5692453678268, 378.84656714030825, 5490.203920431535, 5514.36584792749, 5519.31008623922, 5549.916751248735, 5551.528120109785, 5299.443558426367, 5425.240466334706, 5430.728463576816, 5466.207399927008, 5469.206964990074, 4294.806733696975, 4324.870253892381, 4330.177266319405, 4345.261305578044, 4363.738175709452, 4340.480831708893, 4377.781110944456, 4381.9561804381865, 4383.320585309266, 4422.911541769163, 4308.43990281263, 4335.718178318415, 4336.84821031264, 4350.064736081742, 4381.277089280281, 4244.427002354673, 4272.551382104881, 4286.742795432284, 4304.5910638489295, 4352.107817897323, 3845.8653947111998, 3866.8839934802, 3867.149227170018, 3880.262750077476, 3882.5340959084806, 5273.31446911899, 5314.601404894648, 5321.3348331458565, 5389.541888939445, 5393.379761327419, 4909.373485185409, 5011.398632164127, 5012.423571289312, 5020.874478138039, 5039.319691841628, 5708.828735137952, 5712.600117991742, 5715.714214289269, 5803.245578074865, 5826.1552074948895, 4262.46719160105, 4280.0, 4303.569643035671, 4319.33050377192, 4322.935155972663, 5717.0, 5729.369769325371, 5756.740946657453, 6023.8192854214, 6085.574009819315, 4271.572842715704, 4281.636060934793, 4283.850065247701, 4298.075913678574, 4305.6986010978935, 4081.3673880548563, 4342.131573685276, 4358.8910277242685, 4366.759828209479, 4377.934330985037, 4819.0, 4865.318855360252, 4871.756412179377, 4874.902501949941, 4895.902081958341, 5762.798302059407, 5764.538836893039, 5789.247397838254, 5793.117236898025, 5811.4479124483105, 1479.3564799312333, 1486.6134804950575, 1495.6859059597607, 1497.5657059452853, 1509.411329581464, 945.7871978804714, 948.8007518421209, 962.9085236902483, 976.023976023976, 986.8865080515689, 5003.724795136302, 5015.623828212899, 5015.899682006412, 5022.5685679581875, 5032.622553308516, 5252.29094072303, 5252.711100889412, 5296.675831042239, 5320.680759154463, 5334.306540149833, 4793.400824896888, 4973.303737476756, 4976.676516026447, 5025.095482813055, 5078.1210951948015, 2851.5580085086845, 2855.457463082008, 2865.785066120049, 2869.7130286971137, 2869.8708558114936, 5334.013207556614, 5458.181272809111, 5464.480874316933, 5493.725313734298, 5497.086544131616, 5045.697258164522, 5200.739963001834, 5324.707141107198, 5359.151092872952, 5422.91865622016, 5341.946580534222, 5357.517823395913, 5361.731913404236, 5415.48552887475, 5433.804562996134, 5231.384307846077, 5249.291345668438, 5267.71027593486, 5272.813616936159, 5274.551663108678, 5433.891322173614, 5640.097584386478, 5759.2512973313205, 5845.0, 5911.433470130505, 6066.18106555627, 6127.938720612915, 6131.96934015327, 6139.662318572521, 6141.907871382022, 4221.2190744712325, 4239.724417912826, 4239.766812825324, 4250.36244563318, 4250.787460626894, 5749.281339832521, 5931.881362372642, 5959.612625179349, 5959.76160953557, 5977.910331344946, 5744.712764361681, 5789.971050144891, 5806.216160818404, 5810.244668193108, 5827.1841942127285, 5533.667979921218, 5745.2818397700285, 5753.021986262262, 5798.507126894261, 5817.70911454434, 5716.971415142898, 5730.255066841451, 5733.913991290217, 5742.827715168552, 5753.165790960371, 5835.183074369676, 5842.591018628656, 5852.678102704393, 5868.413158684098, 5889.646621202742, 5504.201890725903, 5690.2887139107615, 5733.942660573341, 5741.62679425836, 5771.7114144293455], "value": 4318.714717817794, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.776750858497, 788.2826627768732, 852.3011130872687, 876.0801158783278, 894.8389289927812, 15935.442259520905, 17041.48875533733, 17465.91267043666, 17480.562985925328, 17508.124593770324, 15598.610034749137, 15661.451849185281, 15666.764998525032, 15697.764533531978, 15936.362545498196, 850.098895171119, 869.9739007829744, 872.720729366603, 875.8248350329927, 876.1019954546578, 871.6644092024553, 872.7861673889906, 872.9519876406795, 874.9387542872001, 875.120503893586, 916.3127654259306, 917.2753524715466, 917.5733284022928, 920.5535315372039, 920.742192186189, 905.5110240470138, 908.4594666173616, 910.0671811393308, 910.1671970147307, 910.3263584947158, 667.933206679333, 668.214847554122, 668.3516988521127, 669.3206395508563, 669.4778073102988, 15089.54731358061, 15203.239838008056, 15224.695506089927, 15262.847371526253, 15278.923605382013, 14362.0, 14398.856011439857, 14701.191434471206, 15054.698906021818, 15095.622609434851, 14064.0, 14202.715945681028, 14284.857151428456, 14416.135031898015, 14680.04579702316, 4768.451628062748, 4847.054824309261, 4856.490068542787, 4905.632077594195, 4967.10592093423, 46335.30497042547, 73736.0, 74179.62910185469, 77291.0, 83106.99999999939, 11609.59366422188, 12572.371381431074, 12594.81107783384, 12743.235405875677, 12793.68015799594, 22350.776492235196, 22366.552668946857, 22413.66379504309, 22434.551308974056, 22438.551228975655, 9307.348485605942, 9364.812703745887, 9415.90584094164, 9579.856302155473, 9738.610455581838, 4964.900701986012, 5028.421731500851, 5041.269015992661, 5133.974330128325, 5166.405863325691, 3798.9620103798607, 3856.0552664597353, 3863.903402414906, 3908.90227744303, 3909.843606255775, 672.0, 689.4243306838846, 700.023467263165, 706.7385067524946, 720.4308596208937, 710.399712243164, 724.1961422820665, 764.6635480388611, 777.149021821105, 823.9340852731767, 10923.890761092287, 11036.172287078502, 11345.829812552818, 11437.565582371373, 11502.194846360842, 804.4851295171095, 830.3025458614793, 830.4187069051688, 835.9874601880977, 838.1074156023936, 5142.460041695643, 5150.4334523202415, 5206.245094461282, 5219.817306394258, 5316.840494785162, 74485.6275718618, 75852.62073689596, 75896.62051689706, 76528.0, 123503.38248308701, 113000.43499782449, 113397.43301283606, 114641.0, 114909.4254528722, 115561.0, 79206.20793792103, 80711.59644201741, 81306.59346703347, 89909.0, 98646.0, 10393.740156496147, 10538.841917371092, 10544.26190166681, 10690.144788417063, 11021.44892755343, 52416.0, 52797.208041878635, 54153.0, 54493.45506545043, 54561.1815822755, 52633.7368313156, 76214.0, 77043.61478192573, 78329.0, 78935.0, 13634.250116243305, 13715.794263086262, 13929.094608850392, 14018.439262429389, 14295.92852035733, 15844.76232856485, 15966.0, 16647.25087371047, 16703.081330526937, 17177.0, 14643.853561464675, 14677.706445871236, 15121.470748523749, 15730.528084157493, 15731.842681573495, 14071.366788494337, 14816.185109818633, 15018.048381628752, 15192.392304307705, 15352.07887526752, 14257.928710356382, 14928.552143435714, 15096.773548397003, 15243.847561524242, 15390.69218615599, 12347.938260308641, 12534.749305014031, 12675.17611355259, 13050.738985220432, 13165.144265623087, 11673.35796531165, 11815.763684726086, 12096.153269271419, 12141.150119491904, 12144.710519539796], "value": 8131.109192892641, "unit": "score"}], "name": "dromaeo_css", "value": 7596.1592426478655, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 110.0, 110.0, 110.0, 110.0, 108.0, 109.0, 109.0, 110.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 126.0, 174.0, 125.0, 125.0, 155.0, 125.0, 161.0, 125.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 118.0, 74.0, 75.0, 74.0, 75.0, 106.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 117.0, 83.0, 84.0, 83.0, 125.0, 83.0, 85.0, 128.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 148.0, 145.0, 145.0, 146.0, 148.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 56.0, 54.0, 50.0, 51.0, 53.0, 51.0, 51.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 77.0, 76.0, 78.0, 77.0, 76.0, 108.0, 77.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 118.0, 121.0, 119.0, 116.0, 129.0, 132.0, 130.0, 120.0], "value": 123.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 151.0, 157.0, 155.0, 153.0, 160.0, 155.0, 152.0, 150.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 64.0, 65.0, 63.0, 64.0, 62.0, 62.0, 62.0, 60.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1474.9999999999998, "alertThreshold": 2.0}]} 03:49:43 INFO - Return code: 0 03:49:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:44 ERROR - # TBPL SUCCESS # 03:49:44 INFO - Running post-action listener: _resource_record_post_action 03:49:44 INFO - ##### 03:49:44 INFO - ##### Finished run-tests step (success) 03:49:44 INFO - ##### 03:49:44 INFO - Running post-run listener: _resource_record_post_run 03:49:44 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 262144; Write bytes: 331767808; Read time: 8; Write time: 162096 03:49:44 INFO - TinderboxPrint: CPU usage
13.1% 03:49:44 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 8 03:49:44 INFO - TinderboxPrint: I/O write bytes / time
331,767,808 / 162,096 03:49:44 INFO - TinderboxPrint: CPU idle
8,251.9 (86.9%) 03:49:44 INFO - TinderboxPrint: CPU user
1,201.6 (12.7%) 03:49:44 INFO - TinderboxPrint: Swap in / out
0 / 0 03:49:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77815808; Read time: 0; Write time: 37468 03:49:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 253952000; Read time: 8; Write time: 124628 03:49:44 INFO - Running post-run listener: _upload_blobber_files 03:49:44 INFO - Blob upload gear active. 03:49:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:45 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:48 INFO - (blobuploader) - INFO - Done attempting. 03:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:49 INFO - (blobuploader) - INFO - Done attempting. 03:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:49 INFO - (blobuploader) - INFO - Done attempting. 03:49:49 INFO - (blobuploader) - INFO - Iteration through files over. 03:49:49 INFO - Return code: 0 03:49:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:49:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:49:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72983792ce7974f4fff9446b508ab0a55e080bfea508600eaed2c83eae1133fe3d40e1649115fe6fcc98f27e9ece456e45996cb2248faee131fb9e8f4676ef8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76c14cefb21623269f06a29552f23780b37d83c6b00e1c69b1477909d053839728f05ad4fb4d845377997b3bb94cf54f2e038eee21b4440ccf8f20903ee69812", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9af2484bd1d10b76e8ac85ef47b11f647cc357d20fc91e875b4c743ae291600600b3bf5556cfd2105453373747e5e409a2b388ea4bcc0831760a2d6a9dfa62d9"} 03:49:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:49:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:49:49 INFO - Contents: 03:49:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72983792ce7974f4fff9446b508ab0a55e080bfea508600eaed2c83eae1133fe3d40e1649115fe6fcc98f27e9ece456e45996cb2248faee131fb9e8f4676ef8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76c14cefb21623269f06a29552f23780b37d83c6b00e1c69b1477909d053839728f05ad4fb4d845377997b3bb94cf54f2e038eee21b4440ccf8f20903ee69812", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9af2484bd1d10b76e8ac85ef47b11f647cc357d20fc91e875b4c743ae291600600b3bf5556cfd2105453373747e5e409a2b388ea4bcc0831760a2d6a9dfa62d9"} 03:49:49 INFO - Running post-run listener: copy_logs_to_upload_dir 03:49:49 INFO - Copying logs to upload dir... 03:49:49 INFO - mkdir: /builds/slave/test/build/upload/logs 03:49:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.421085 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-15 03:49:47.896211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 03:49:47.899745) ========= 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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 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/f72983792ce7974f4fff9446b508ab0a55e080bfea508600eaed2c83eae1133fe3d40e1649115fe6fcc98f27e9ece456e45996cb2248faee131fb9e8f4676ef8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76c14cefb21623269f06a29552f23780b37d83c6b00e1c69b1477909d053839728f05ad4fb4d845377997b3bb94cf54f2e038eee21b4440ccf8f20903ee69812", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9af2484bd1d10b76e8ac85ef47b11f647cc357d20fc91e875b4c743ae291600600b3bf5556cfd2105453373747e5e409a2b388ea4bcc0831760a2d6a9dfa62d9"} build_url:https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/IRU_KHsQS1iI7t6O4zKOSw/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/f72983792ce7974f4fff9446b508ab0a55e080bfea508600eaed2c83eae1133fe3d40e1649115fe6fcc98f27e9ece456e45996cb2248faee131fb9e8f4676ef8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76c14cefb21623269f06a29552f23780b37d83c6b00e1c69b1477909d053839728f05ad4fb4d845377997b3bb94cf54f2e038eee21b4440ccf8f20903ee69812", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9af2484bd1d10b76e8ac85ef47b11f647cc357d20fc91e875b4c743ae291600600b3bf5556cfd2105453373747e5e409a2b388ea4bcc0831760a2d6a9dfa62d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 03:49:47.927315) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:49:47.927679) ========= 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=d2a8c569965ab8367867b893000001a1-1465973848.510247-25863408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 03:49:48.013145) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 03:49:48.013565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 03:49:48.014100) ========= ========= Total master_lag: 0.17 =========