builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1467544020.59 results: success (0) buildid: 20160703011021 builduid: a7e0a85ce501428586fbf3e562c1bece revision: b9faa10c9918a271440acd6233028ba10c0ae1ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.593231) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.593621) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.593901) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.624766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.625029) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-03 04:07:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-07-03 04:07:01 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107403 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.747006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.747313) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020022 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:00.797044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 04:07:00.797375) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9faa10c9918a271440acd6233028ba10c0ae1ac --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 b9faa10c9918a271440acd6233028ba10c0ae1ac --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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-03 04:07:01,496 truncating revision to first 12 chars 2016-07-03 04:07:01,496 Setting DEBUG logging. 2016-07-03 04:07:01,496 attempt 1/10 2016-07-03 04:07:01,496 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9faa10c9918?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-03 04:07:02,255 unpacking tar archive at: mozilla-aurora-b9faa10c9918/testing/mozharness/ program finished with exit code 0 elapsedTime=1.095210 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 04:07:01.906580) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:01.906883) ========= script_repo_revision: b9faa10c9918a271440acd6233028ba10c0ae1ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:01.907286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:01.907565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 04:07:01.917996) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-03 04:07:01.918361) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:07:02 INFO - MultiFileLogger online at 20160703 04:07:02 in /builds/slave/test 04:07:02 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 04:07:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:07:02 INFO - {'append_to_log': False, 04:07:02 INFO - 'base_work_dir': '/builds/slave/test', 04:07:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:07:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:07:02 INFO - 'branch': 'Mozilla-Aurora', 04:07:02 INFO - 'buildbot_json_path': 'buildprops.json', 04:07:02 INFO - 'config_files': ('talos/linux_config.py',), 04:07:02 INFO - 'default_actions': ('clobber', 04:07:02 INFO - 'read-buildbot-config', 04:07:02 INFO - 'download-and-extract', 04:07:02 INFO - 'populate-webroot', 04:07:02 INFO - 'create-virtualenv', 04:07:02 INFO - 'install', 04:07:02 INFO - 'run-tests'), 04:07:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:07:02 INFO - 'download_minidump_stackwalk': True, 04:07:02 INFO - 'download_symbols': 'ondemand', 04:07:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:07:02 INFO - 'tooltool.py': '/tools/tooltool.py', 04:07:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:07:02 INFO - '/tools/misc-python/virtualenv.py')}, 04:07:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:07:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:07:02 INFO - 'installer_path': 'installer.exe', 04:07:02 INFO - 'log_level': 'info', 04:07:02 INFO - 'log_name': 'talos', 04:07:02 INFO - 'log_to_console': True, 04:07:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:07:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:07:02 INFO - 'opt_config_files': (), 04:07:02 INFO - 'pip_index': False, 04:07:02 INFO - 'sps_profile': False, 04:07:02 INFO - 'sps_profile_interval': 0, 04:07:02 INFO - 'suite': 'dromaeojs', 04:07:02 INFO - 'system_bits': '32', 04:07:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:07:02 INFO - 'title': 'talos-linux64-ix-006', 04:07:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:07:02 INFO - 'use_talos_json': True, 04:07:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:07:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:07:02 INFO - 'work_dir': 'build'} 04:07:02 INFO - ##### 04:07:02 INFO - ##### Running clobber step. 04:07:02 INFO - ##### 04:07:02 INFO - Running pre-action listener: _resource_record_pre_action 04:07:02 INFO - Running main action method: clobber 04:07:02 INFO - rmtree: /builds/slave/test/build 04:07:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:07:03 INFO - Running post-action listener: _resource_record_post_action 04:07:03 INFO - ##### 04:07:03 INFO - ##### Finished clobber step (success) 04:07:03 INFO - ##### 04:07:03 INFO - ##### 04:07:03 INFO - ##### Running read-buildbot-config step. 04:07:03 INFO - ##### 04:07:03 INFO - Running pre-action listener: _resource_record_pre_action 04:07:03 INFO - Running main action method: read_buildbot_config 04:07:03 INFO - Using buildbot properties: 04:07:03 INFO - { 04:07:03 INFO - "project": "", 04:07:03 INFO - "product": "firefox", 04:07:03 INFO - "script_repo_revision": "production", 04:07:03 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:07:03 INFO - "repository": "", 04:07:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:07:03 INFO - "buildid": "20160703011021", 04:07:03 INFO - "pgo_build": "True", 04:07:03 INFO - "basedir": "/builds/slave/test", 04:07:03 INFO - "buildnumber": 342, 04:07:03 INFO - "slavename": "talos-linux64-ix-006", 04:07:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:07:03 INFO - "platform": "ubuntu64_hw", 04:07:03 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:07:03 INFO - "branch": "mozilla-aurora", 04:07:03 INFO - "repo_path": "releases/mozilla-aurora", 04:07:03 INFO - "revision": "b9faa10c9918a271440acd6233028ba10c0ae1ac", 04:07:03 INFO - "stage_platform": "linux64-pgo", 04:07:03 INFO - "builduid": "a7e0a85ce501428586fbf3e562c1bece", 04:07:03 INFO - "slavebuilddir": "test" 04:07:03 INFO - } 04:07:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 04:07:03 INFO - Running post-action listener: _resource_record_post_action 04:07:03 INFO - ##### 04:07:03 INFO - ##### Finished read-buildbot-config step (success) 04:07:03 INFO - ##### 04:07:03 INFO - ##### 04:07:03 INFO - ##### Running download-and-extract step. 04:07:03 INFO - ##### 04:07:03 INFO - Running pre-action listener: _resource_record_pre_action 04:07:03 INFO - Running main action method: download_and_extract 04:07:03 INFO - mkdir: /builds/slave/test/build/tests 04:07:03 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')]} 04:07:03 INFO - https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:07:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 04:07:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 04:07:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:04 INFO - Downloaded 1361 bytes. 04:07:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 04:07:04 INFO - Using the following test package requirements: 04:07:04 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:04 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:07:04 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:07:04 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'jsshell-linux-x86_64.zip'], 04:07:04 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:07:04 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:04 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:07:04 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:07:04 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:07:04 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:04 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:07:04 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:04 INFO - https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 04:07:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 04:07:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:05 INFO - Downloaded 24525015 bytes. 04:07:05 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'] 04:07:05 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 04:07:07 INFO - Return code: 0 04:07:07 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 04:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:07 INFO - https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 04:07:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 04:07:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:12 INFO - Downloaded 24525015 bytes. 04:07:12 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'] 04:07:12 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 04:07:14 INFO - Return code: 0 04:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:14 INFO - https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:07:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 04:07:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 04:07:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:15 INFO - Downloaded 10833369 bytes. 04:07:15 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'] 04:07:15 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 04:07:15 INFO - Return code: 0 04:07: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')]} 04:07:15 INFO - https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:07:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 04:07:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 04:07:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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 04:07:17 INFO - Downloaded 63635245 bytes. 04:07:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 04:07:17 INFO - mkdir: /builds/slave/test/properties 04:07:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:07:17 INFO - Writing to file /builds/slave/test/properties/build_url 04:07:17 INFO - Contents: 04:07:17 INFO - build_url:https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 04:07:17 INFO - Running post-action listener: _resource_record_post_action 04:07:17 INFO - Running post-action listener: set_extra_try_arguments 04:07:17 INFO - ##### 04:07:17 INFO - ##### Finished download-and-extract step (success) 04:07:17 INFO - ##### 04:07:17 INFO - ##### 04:07:17 INFO - ##### Running populate-webroot step. 04:07:17 INFO - ##### 04:07:17 INFO - Running pre-action listener: _resource_record_pre_action 04:07:17 INFO - Running main action method: populate_webroot 04:07:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:07:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:07:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:07:17 INFO - '--tppagecycles', 04:07:17 INFO - '7'], 04:07:17 INFO - 'tests': ['tsvgm']}}, 04:07:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:07:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:07:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:07:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:07:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:07:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:07:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['damp', 'tps']}, 04:07:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'tests': ['damp', 'tps']}, 04:07:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['dromaeo_dom']}, 04:07:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:07:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'other-e10s': {'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['a11yr', 04:07:17 INFO - 'ts_paint', 04:07:17 INFO - 'tpaint', 04:07:17 INFO - 'sessionrestore', 04:07:17 INFO - 'sessionrestore_no_auto_restore', 04:07:17 INFO - 'tabpaint']}, 04:07:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['tsvgx', 04:07:17 INFO - 'tsvgr_opacity', 04:07:17 INFO - 'tart', 04:07:17 INFO - 'tscrollx', 04:07:17 INFO - 'cart']}, 04:07:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:07:17 INFO - 'tsvgr_opacity', 04:07:17 INFO - 'tart', 04:07:17 INFO - 'tscrollx', 04:07:17 INFO - 'cart']}, 04:07:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'talos_options': ['--disable-e10s'], 04:07:17 INFO - 'tests': ['tp5o']}, 04:07:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'tests': ['tp5o']}, 04:07:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'talos_options': ['--disable-e10s', 04:07:17 INFO - '--xperf_path', 04:07:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:07:17 INFO - 'tests': ['tp5n']}, 04:07:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:07:17 INFO - 'talos_options': ['--xperf_path', 04:07:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:07:17 INFO - 'tests': ['tp5n']}}, 04:07:17 INFO - 'talos.zip': {'path': '', 04:07:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:07:17 INFO - Running post-action listener: _resource_record_post_action 04:07:17 INFO - ##### 04:07:17 INFO - ##### Finished populate-webroot step (success) 04:07:17 INFO - ##### 04:07:17 INFO - ##### 04:07:17 INFO - ##### Running create-virtualenv step. 04:07:17 INFO - ##### 04:07:17 INFO - Running pre-action listener: _resource_record_pre_action 04:07:17 INFO - Running main action method: create_virtualenv 04:07:17 INFO - Creating virtualenv /builds/slave/test/build/venv 04:07:17 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 04:07:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:07:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:07:18 INFO - Using real prefix '/usr' 04:07:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:07:18 INFO - Installing distribute.............................................................................................................................................................................................done. 04:07:20 INFO - Installing pip.................done. 04:07:20 INFO - Return code: 0 04:07:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:07:20 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')]} 04:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:20 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')]} 04:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:20 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:20 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 04:07:20 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 04:07:20 INFO - Using env: {'DISPLAY': ':0', 04:07:20 INFO - 'HOME': '/home/cltbld', 04:07:20 INFO - 'LANG': 'en_US.UTF-8', 04:07:20 INFO - 'LANGUAGE': 'en_US:en', 04:07:20 INFO - 'LOGNAME': 'cltbld', 04:07:20 INFO - 'MAIL': '/var/mail/cltbld', 04:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:20 INFO - 'MOZ_NO_REMOTE': '1', 04:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:20 INFO - 'NO_EM_RESTART': '1', 04:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:20 INFO - 'PWD': '/builds/slave/test', 04:07:20 INFO - 'SHELL': '/bin/bash', 04:07:20 INFO - 'SHLVL': '1', 04:07:20 INFO - 'TERM': 'linux', 04:07:20 INFO - 'TMOUT': '86400', 04:07:20 INFO - 'USER': 'cltbld', 04:07:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:20 INFO - '_': '/tools/buildbot/bin/python'} 04:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:20 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 04:07:20 INFO - Cleaning up... 04:07:20 INFO - Return code: 0 04:07:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:07:20 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')]} 04:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:20 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')]} 04:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:20 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:20 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 04:07:20 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 04:07:20 INFO - Using env: {'DISPLAY': ':0', 04:07:20 INFO - 'HOME': '/home/cltbld', 04:07:20 INFO - 'LANG': 'en_US.UTF-8', 04:07:20 INFO - 'LANGUAGE': 'en_US:en', 04:07:20 INFO - 'LOGNAME': 'cltbld', 04:07:20 INFO - 'MAIL': '/var/mail/cltbld', 04:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:20 INFO - 'MOZ_NO_REMOTE': '1', 04:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:20 INFO - 'NO_EM_RESTART': '1', 04:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:20 INFO - 'PWD': '/builds/slave/test', 04:07:20 INFO - 'SHELL': '/bin/bash', 04:07:20 INFO - 'SHLVL': '1', 04:07:20 INFO - 'TERM': 'linux', 04:07:20 INFO - 'TMOUT': '86400', 04:07:20 INFO - 'USER': 'cltbld', 04:07:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:20 INFO - '_': '/tools/buildbot/bin/python'} 04:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:20 INFO - Downloading/unpacking psutil>=3.1.1 04:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:07:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:07:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:07:22 INFO - Installing collected packages: psutil 04:07:22 INFO - Running setup.py install for psutil 04:07:23 INFO - building 'psutil._psutil_linux' extension 04:07:23 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 04:07:23 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 04:07:23 INFO - building 'psutil._psutil_posix' extension 04:07:23 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 04:07:23 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 04:07:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:07:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:07:23 INFO - Successfully installed psutil 04:07:23 INFO - Cleaning up... 04:07:23 INFO - Return code: 0 04:07:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:07:23 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')]} 04:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:23 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')]} 04:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:23 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:23 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 04:07:23 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 04:07:23 INFO - Using env: {'DISPLAY': ':0', 04:07:23 INFO - 'HOME': '/home/cltbld', 04:07:23 INFO - 'LANG': 'en_US.UTF-8', 04:07:23 INFO - 'LANGUAGE': 'en_US:en', 04:07:23 INFO - 'LOGNAME': 'cltbld', 04:07:23 INFO - 'MAIL': '/var/mail/cltbld', 04:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:23 INFO - 'MOZ_NO_REMOTE': '1', 04:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:23 INFO - 'NO_EM_RESTART': '1', 04:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:23 INFO - 'PWD': '/builds/slave/test', 04:07:23 INFO - 'SHELL': '/bin/bash', 04:07:23 INFO - 'SHLVL': '1', 04:07:23 INFO - 'TERM': 'linux', 04:07:23 INFO - 'TMOUT': '86400', 04:07:23 INFO - 'USER': 'cltbld', 04:07:23 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:23 INFO - '_': '/tools/buildbot/bin/python'} 04:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:07:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:07:25 INFO - Installing collected packages: mozsystemmonitor 04:07:25 INFO - Running setup.py install for mozsystemmonitor 04:07:25 INFO - Successfully installed mozsystemmonitor 04:07:25 INFO - Cleaning up... 04:07:25 INFO - Return code: 0 04:07:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:25 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:25 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 04:07:25 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 04:07:25 INFO - Using env: {'DISPLAY': ':0', 04:07:25 INFO - 'HOME': '/home/cltbld', 04:07:25 INFO - 'LANG': 'en_US.UTF-8', 04:07:25 INFO - 'LANGUAGE': 'en_US:en', 04:07:25 INFO - 'LOGNAME': 'cltbld', 04:07:25 INFO - 'MAIL': '/var/mail/cltbld', 04:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:25 INFO - 'MOZ_NO_REMOTE': '1', 04:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:25 INFO - 'NO_EM_RESTART': '1', 04:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:25 INFO - 'PWD': '/builds/slave/test', 04:07:25 INFO - 'SHELL': '/bin/bash', 04:07:25 INFO - 'SHLVL': '1', 04:07:25 INFO - 'TERM': 'linux', 04:07:25 INFO - 'TMOUT': '86400', 04:07:25 INFO - 'USER': 'cltbld', 04:07:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:25 INFO - '_': '/tools/buildbot/bin/python'} 04:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:26 INFO - Downloading/unpacking jsonschema==2.5.1 04:07:26 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 04:07:26 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 04:07:26 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 04:07:26 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 04:07:26 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 04:07:26 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 04:07:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:07:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:07:27 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 04:07:27 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 04:07:27 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 04:07:27 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 04:07:27 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 04:07:27 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 04:07:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:07:28 INFO - warning: no files found matching '*.txt' 04:07:28 INFO - no previously-included directories found matching 'build' 04:07:28 INFO - no previously-included directories found matching 'dist' 04:07:28 INFO - no previously-included directories found matching '.git*' 04:07:28 INFO - Installing collected packages: jsonschema, functools32 04:07:28 INFO - Running setup.py install for functools32 04:07:28 INFO - warning: no files found matching '*.txt' 04:07:28 INFO - no previously-included directories found matching 'build' 04:07:28 INFO - no previously-included directories found matching 'dist' 04:07:28 INFO - no previously-included directories found matching '.git*' 04:07:28 INFO - Successfully installed jsonschema functools32 04:07:28 INFO - Cleaning up... 04:07:28 INFO - Return code: 0 04:07:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:28 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:28 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 04:07:28 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 04:07:28 INFO - Using env: {'DISPLAY': ':0', 04:07:28 INFO - 'HOME': '/home/cltbld', 04:07:28 INFO - 'LANG': 'en_US.UTF-8', 04:07:28 INFO - 'LANGUAGE': 'en_US:en', 04:07:28 INFO - 'LOGNAME': 'cltbld', 04:07:28 INFO - 'MAIL': '/var/mail/cltbld', 04:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:28 INFO - 'MOZ_NO_REMOTE': '1', 04:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:28 INFO - 'NO_EM_RESTART': '1', 04:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:28 INFO - 'PWD': '/builds/slave/test', 04:07:28 INFO - 'SHELL': '/bin/bash', 04:07:28 INFO - 'SHLVL': '1', 04:07:28 INFO - 'TERM': 'linux', 04:07:28 INFO - 'TMOUT': '86400', 04:07:28 INFO - 'USER': 'cltbld', 04:07:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:28 INFO - '_': '/tools/buildbot/bin/python'} 04:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:28 INFO - Downloading/unpacking blobuploader==1.2.4 04:07:28 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 04:07:28 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 04:07:28 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 04:07:28 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 04:07:28 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 04:07:28 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 04:07:30 INFO - Downloading blobuploader-1.2.4.tar.gz 04:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:07:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:07:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:07:31 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 04:07:31 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 04:07:31 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 04:07:31 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 04:07:31 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 04:07:31 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 04:07:31 INFO - Downloading docopt-0.6.1.tar.gz 04:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:07:31 INFO - Installing collected packages: blobuploader, requests, docopt 04:07:31 INFO - Running setup.py install for blobuploader 04:07:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:07:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:07:31 INFO - Running setup.py install for requests 04:07:31 INFO - Running setup.py install for docopt 04:07:31 INFO - Successfully installed blobuploader requests docopt 04:07:31 INFO - Cleaning up... 04:07:31 INFO - Return code: 0 04:07:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07:31 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')]} 04:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:31 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')]} 04:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:31 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:31 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 04:07:31 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 04:07:31 INFO - Using env: {'DISPLAY': ':0', 04:07:31 INFO - 'HOME': '/home/cltbld', 04:07:31 INFO - 'LANG': 'en_US.UTF-8', 04:07:31 INFO - 'LANGUAGE': 'en_US:en', 04:07:31 INFO - 'LOGNAME': 'cltbld', 04:07:31 INFO - 'MAIL': '/var/mail/cltbld', 04:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:31 INFO - 'MOZ_NO_REMOTE': '1', 04:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:31 INFO - 'NO_EM_RESTART': '1', 04:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:31 INFO - 'PWD': '/builds/slave/test', 04:07:31 INFO - 'SHELL': '/bin/bash', 04:07:31 INFO - 'SHLVL': '1', 04:07:31 INFO - 'TERM': 'linux', 04:07:31 INFO - 'TMOUT': '86400', 04:07:31 INFO - 'USER': 'cltbld', 04:07:31 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:31 INFO - '_': '/tools/buildbot/bin/python'} 04:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:07:32 INFO - Running setup.py (path:/tmp/pip-ZXpOhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:07:32 INFO - Running setup.py (path:/tmp/pip-kPUv8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:07:32 INFO - Running setup.py (path:/tmp/pip-w349pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:07:32 INFO - Running setup.py (path:/tmp/pip-VuahqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:07:32 INFO - Running setup.py (path:/tmp/pip-EId7oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:07:32 INFO - Running setup.py (path:/tmp/pip-6lt11q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:07:32 INFO - Running setup.py (path:/tmp/pip-O452Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:07:32 INFO - Running setup.py (path:/tmp/pip-16VfIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:07:32 INFO - Running setup.py (path:/tmp/pip-SZYcFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:07:32 INFO - Running setup.py (path:/tmp/pip-dfegMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:07:32 INFO - Running setup.py (path:/tmp/pip-Rs2Z0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:07:32 INFO - Running setup.py (path:/tmp/pip-syY7z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:07:32 INFO - Running setup.py (path:/tmp/pip-SOIdIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:07:33 INFO - Running setup.py (path:/tmp/pip-3k57Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:33 INFO - Running setup.py (path:/tmp/pip-WcH8HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:07:33 INFO - Running setup.py (path:/tmp/pip-iyXHHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:07:33 INFO - Running setup.py (path:/tmp/pip-Ry4r35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:07:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:07:33 INFO - Running setup.py install for manifestparser 04:07:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:07:33 INFO - Running setup.py install for mozcrash 04:07:33 INFO - Running setup.py install for mozdebug 04:07:33 INFO - Running setup.py install for mozdevice 04:07:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:07:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:07:33 INFO - Running setup.py install for mozfile 04:07:33 INFO - Running setup.py install for mozhttpd 04:07:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:07:33 INFO - Running setup.py install for mozinfo 04:07:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for mozInstall 04:07:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for mozleak 04:07:34 INFO - Running setup.py install for mozlog 04:07:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for moznetwork 04:07:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for mozprocess 04:07:34 INFO - Running setup.py install for mozprofile 04:07:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for mozrunner 04:07:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:07:34 INFO - Running setup.py install for mozscreenshot 04:07:34 INFO - Running setup.py install for moztest 04:07:35 INFO - Running setup.py install for mozversion 04:07:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:07:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:07:35 INFO - Cleaning up... 04:07:35 INFO - Return code: 0 04:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07: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')]} 04:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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')]} 04:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:35 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:35 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 04:07:35 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 04:07:35 INFO - Using env: {'DISPLAY': ':0', 04:07:35 INFO - 'HOME': '/home/cltbld', 04:07:35 INFO - 'LANG': 'en_US.UTF-8', 04:07:35 INFO - 'LANGUAGE': 'en_US:en', 04:07:35 INFO - 'LOGNAME': 'cltbld', 04:07:35 INFO - 'MAIL': '/var/mail/cltbld', 04:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:35 INFO - 'MOZ_NO_REMOTE': '1', 04:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:35 INFO - 'NO_EM_RESTART': '1', 04:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:35 INFO - 'PWD': '/builds/slave/test', 04:07:35 INFO - 'SHELL': '/bin/bash', 04:07:35 INFO - 'SHLVL': '1', 04:07:35 INFO - 'TERM': 'linux', 04:07:35 INFO - 'TMOUT': '86400', 04:07:35 INFO - 'USER': 'cltbld', 04:07:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:35 INFO - '_': '/tools/buildbot/bin/python'} 04:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:07:35 INFO - Running setup.py (path:/tmp/pip-CZqOeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:07:35 INFO - Running setup.py (path:/tmp/pip-9ttD6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:07:35 INFO - Running setup.py (path:/tmp/pip-cEXkT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:07:35 INFO - Running setup.py (path:/tmp/pip-souDrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:07:35 INFO - Running setup.py (path:/tmp/pip-oR9Fej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:07:35 INFO - Running setup.py (path:/tmp/pip-iP96lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:07:35 INFO - Running setup.py (path:/tmp/pip-uxS9Il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:07:35 INFO - Running setup.py (path:/tmp/pip-SoQK43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:07:35 INFO - Running setup.py (path:/tmp/pip-I8T37C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:07:35 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)) 04:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:07:36 INFO - Running setup.py (path:/tmp/pip-ij_7wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:07:36 INFO - Running setup.py (path:/tmp/pip-X2pT7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:07:36 INFO - Running setup.py (path:/tmp/pip-R6ZiiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:07:36 INFO - Running setup.py (path:/tmp/pip-Tb_Czp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:07:36 INFO - Running setup.py (path:/tmp/pip-yGOj7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:36 INFO - Running setup.py (path:/tmp/pip-TkKcq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:07:36 INFO - Running setup.py (path:/tmp/pip-22exjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:07:36 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)) 04:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:07:36 INFO - Running setup.py (path:/tmp/pip-R1sAaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:07:36 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)) 04:07:36 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)) 04:07:36 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)) 04:07:36 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)) 04:07:36 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)) 04:07:36 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)) 04:07:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:38 INFO - Downloading blessings-1.6.tar.gz 04:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:07:38 INFO - Installing collected packages: blessings 04:07:38 INFO - Running setup.py install for blessings 04:07:38 INFO - Successfully installed blessings 04:07:38 INFO - Cleaning up... 04:07:38 INFO - Return code: 0 04:07:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:07:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:07:38 INFO - Reading from file tmpfile_stdout 04:07:38 INFO - Current package versions: 04:07:38 INFO - argparse == 1.2.1 04:07:38 INFO - blessings == 1.6 04:07:38 INFO - blobuploader == 1.2.4 04:07:38 INFO - docopt == 0.6.1 04:07:38 INFO - functools32 == 3.2.3-2 04:07:38 INFO - jsonschema == 2.5.1 04:07:38 INFO - manifestparser == 1.1 04:07:38 INFO - mozInstall == 1.12 04:07:38 INFO - mozcrash == 0.17 04:07:38 INFO - mozdebug == 0.1 04:07:38 INFO - mozdevice == 0.48 04:07:38 INFO - mozfile == 1.2 04:07:38 INFO - mozhttpd == 0.7 04:07:38 INFO - mozinfo == 0.9 04:07:38 INFO - mozleak == 0.1 04:07:38 INFO - mozlog == 3.2 04:07:38 INFO - moznetwork == 0.27 04:07:38 INFO - mozprocess == 0.22 04:07:38 INFO - mozprofile == 0.28 04:07:38 INFO - mozrunner == 6.11 04:07:38 INFO - mozscreenshot == 0.1 04:07:38 INFO - mozsystemmonitor == 0.3 04:07:38 INFO - moztest == 0.7 04:07:38 INFO - mozversion == 1.4 04:07:38 INFO - psutil == 3.1.1 04:07:38 INFO - requests == 1.2.3 04:07:38 INFO - wsgiref == 0.1.2 04:07:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07: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')]} 04:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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')]} 04:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:38 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:38 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 04:07:38 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 04:07:38 INFO - Using env: {'DISPLAY': ':0', 04:07:38 INFO - 'HOME': '/home/cltbld', 04:07:38 INFO - 'LANG': 'en_US.UTF-8', 04:07:38 INFO - 'LANGUAGE': 'en_US:en', 04:07:38 INFO - 'LOGNAME': 'cltbld', 04:07:38 INFO - 'MAIL': '/var/mail/cltbld', 04:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:38 INFO - 'MOZ_NO_REMOTE': '1', 04:07:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:38 INFO - 'NO_EM_RESTART': '1', 04:07:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:38 INFO - 'PWD': '/builds/slave/test', 04:07:38 INFO - 'SHELL': '/bin/bash', 04:07:38 INFO - 'SHLVL': '1', 04:07:38 INFO - 'TERM': 'linux', 04:07:38 INFO - 'TMOUT': '86400', 04:07:38 INFO - 'USER': 'cltbld', 04:07:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:38 INFO - '_': '/tools/buildbot/bin/python'} 04:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 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)) 04:07:39 INFO - Cleaning up... 04:07:39 INFO - Return code: 0 04:07:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:07:39 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')]} 04:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:39 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')]} 04:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:39 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:39 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 04:07:39 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 04:07:39 INFO - Using env: {'DISPLAY': ':0', 04:07:39 INFO - 'HOME': '/home/cltbld', 04:07:39 INFO - 'LANG': 'en_US.UTF-8', 04:07:39 INFO - 'LANGUAGE': 'en_US:en', 04:07:39 INFO - 'LOGNAME': 'cltbld', 04:07:39 INFO - 'MAIL': '/var/mail/cltbld', 04:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:39 INFO - 'MOZ_NO_REMOTE': '1', 04:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:39 INFO - 'NO_EM_RESTART': '1', 04:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:39 INFO - 'PWD': '/builds/slave/test', 04:07:39 INFO - 'SHELL': '/bin/bash', 04:07:39 INFO - 'SHLVL': '1', 04:07:39 INFO - 'TERM': 'linux', 04:07:39 INFO - 'TMOUT': '86400', 04:07:39 INFO - 'USER': 'cltbld', 04:07:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:39 INFO - '_': '/tools/buildbot/bin/python'} 04:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:07:39 INFO - Cleaning up... 04:07:39 INFO - Return code: 0 04:07:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:07:39 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')]} 04:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:39 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')]} 04:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:39 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 0x7fa523c39f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', '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 04:07:39 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 04:07:39 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 04:07:39 INFO - Using env: {'DISPLAY': ':0', 04:07:39 INFO - 'HOME': '/home/cltbld', 04:07:39 INFO - 'LANG': 'en_US.UTF-8', 04:07:39 INFO - 'LANGUAGE': 'en_US:en', 04:07:39 INFO - 'LOGNAME': 'cltbld', 04:07:39 INFO - 'MAIL': '/var/mail/cltbld', 04:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:39 INFO - 'MOZ_NO_REMOTE': '1', 04:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:39 INFO - 'NO_EM_RESTART': '1', 04:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:39 INFO - 'PWD': '/builds/slave/test', 04:07:39 INFO - 'SHELL': '/bin/bash', 04:07:39 INFO - 'SHLVL': '1', 04:07:39 INFO - 'TERM': 'linux', 04:07:39 INFO - 'TMOUT': '86400', 04:07:39 INFO - 'USER': 'cltbld', 04:07:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:39 INFO - '_': '/tools/buildbot/bin/python'} 04:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:39 INFO - Downloading/unpacking flake8 04:07:39 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 04:07:39 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 04:07:39 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 04:07:39 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 04:07:39 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 04:07:39 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 04:07:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:07:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:07:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:07:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:07:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:07:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:07:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:07:42 INFO - no previously-included directories found matching 'docs/_build' 04:07:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:07:42 INFO - Running setup.py install for pep8 04:07:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:07:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:07:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:07:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:07:42 INFO - no previously-included directories found matching 'docs/_build' 04:07:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:07:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:07:42 INFO - Cleaning up... 04:07:42 INFO - Return code: 0 04:07:42 INFO - Running post-action listener: _resource_record_post_action 04:07:42 INFO - Running post-action listener: _start_resource_monitoring 04:07:42 INFO - Starting resource monitoring. 04:07:42 INFO - ##### 04:07:42 INFO - ##### Finished create-virtualenv step (success) 04:07:42 INFO - ##### 04:07:42 INFO - ##### 04:07:42 INFO - ##### Running install step. 04:07:42 INFO - ##### 04:07:42 INFO - Running pre-action listener: _resource_record_pre_action 04:07:42 INFO - Running main action method: install 04:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:07:42 INFO - Reading from file tmpfile_stdout 04:07:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:07:42 INFO - Reading from file tmpfile_stdout 04:07:42 INFO - Output received: 04:07:42 INFO - Usage: mozinstall [options] installer 04:07:42 INFO - Options: 04:07:42 INFO - -h, --help show this help message and exit 04:07:42 INFO - -d DEST, --destination=DEST 04:07:42 INFO - Directory to install application into. [default: 04:07:42 INFO - "/builds/slave/test"] 04:07:42 INFO - --app=APP Application being installed. [default: firefox] 04:07:42 INFO - mkdir: /builds/slave/test/build/application 04:07:42 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'] 04:07:42 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 04:07:58 INFO - Reading from file tmpfile_stdout 04:07:58 INFO - Output received: 04:07:58 INFO - /builds/slave/test/build/application/firefox/firefox 04:07:58 INFO - Running post-action listener: _resource_record_post_action 04:07:58 INFO - ##### 04:07:58 INFO - ##### Finished install step (success) 04:07:58 INFO - ##### 04:07:58 INFO - ##### 04:07:58 INFO - ##### Running run-tests step. 04:07:58 INFO - ##### 04:07:58 INFO - Running pre-action listener: _resource_record_pre_action 04:07:58 INFO - Running main action method: run_tests 04:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:07:58 INFO - Python 2.7.3 04:07:58 INFO - Return code: 0 04:07:58 INFO - grabbing minidump binary from tooltool 04:07:58 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')]} 04:07:58 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 0x21ae2e0>, '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 0x211a140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211a5d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:07:58 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 04:07:58 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 04:07:58 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 04:07:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:07:58 INFO - Return code: 0 04:07:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:07:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:07:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:07:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:07:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:07:59 INFO - Return code: 0 04:07:59 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/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 04:07:59 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/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 04:07:59 INFO - Using env: {'DISPLAY': ':0', 04:07:59 INFO - 'HOME': '/home/cltbld', 04:07:59 INFO - 'LANG': 'en_US.UTF-8', 04:07:59 INFO - 'LANGUAGE': 'en_US:en', 04:07:59 INFO - 'LOGNAME': 'cltbld', 04:07:59 INFO - 'MAIL': '/var/mail/cltbld', 04:07:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:07:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:59 INFO - 'MOZ_NO_REMOTE': '1', 04:07:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:59 INFO - 'NO_EM_RESTART': '1', 04:07:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:59 INFO - 'PWD': '/builds/slave/test', 04:07:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:07:59 INFO - 'SHELL': '/bin/bash', 04:07:59 INFO - 'SHLVL': '1', 04:07:59 INFO - 'TERM': 'linux', 04:07:59 INFO - 'TMOUT': '86400', 04:07:59 INFO - 'USER': 'cltbld', 04:07:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488', 04:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:59 INFO - '_': '/tools/buildbot/bin/python'} 04:07:59 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/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 04:07:59 INFO - mozversion application_buildid: 20160703011021 04:07:59 INFO - mozversion application_changeset: b9faa10c9918a271440acd6233028ba10c0ae1ac 04:07:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:07:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:07:59 INFO - mozversion application_name: Firefox 04:07:59 INFO - mozversion application_remotingname: firefox-dev 04:07:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:07:59 INFO - mozversion application_vendor: Mozilla 04:07:59 INFO - mozversion application_version: 49.0a2 04:07:59 INFO - mozversion platform_buildid: 20160703011021 04:07:59 INFO - mozversion platform_changeset: b9faa10c9918a271440acd6233028ba10c0ae1ac 04:07:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:07:59 INFO - mozversion platform_version: 49.0a2 04:07:59 INFO - using testdate: 1467544079 04:07:59 INFO - actual date: 1467544079 04:07:59 INFO - starting webserver on 'localhost:56931' 04:07:59 INFO - SUITE-START | Running 2 tests 04:07:59 INFO - TEST-START | dromaeo_css 04:07:59 INFO - operating with platform_type : linux_ 04:07:59 INFO - Initialising browser for dromaeo_css test... 04:07:59 INFO - TEST-INFO | started process 9535 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfkVIms/profile http://localhost:56931/getInfo.html) 04:08:03 INFO - PROCESS | 9535 | __metrics Screen width/height:1600/1200 04:08:03 INFO - PROCESS | 9535 | colorDepth:24 04:08:03 INFO - PROCESS | 9535 | Browser inner width/height: 1024/697 04:08:03 INFO - PROCESS | 9535 | __metrics 04:08:04 ERROR - PROCESS | 9535 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:08:05 INFO - TEST-INFO | 9535: exit 0 04:08:05 INFO - Browser initialized. 04:08:05 INFO - Running cycle 1/1 for dromaeo_css test... 04:08:05 INFO - TEST-INFO | started process 9626 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfkVIms/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:08:17 INFO - PROCESS | 9626 | RSS: Main: 145485824 04:08:17 INFO - PROCESS | 9626 | 04:09:08 INFO - PROCESS | 9626 | 1467544148923 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] 04:11:17 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56931/tests/dromaeo/cssquery-ext.html) 04:11:18 INFO - PROCESS | 9626 | RSS: Main: 255758336 04:11:18 INFO - PROCESS | 9626 | 04:14:20 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-ext.html (next: http://localhost:56931/tests/dromaeo/cssquery-jquery.html) 04:14:21 INFO - PROCESS | 9626 | RSS: Main: 234377216 04:14:21 INFO - PROCESS | 9626 | 04:17:22 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56931/tests/dromaeo/cssquery-mootools.html) 04:17:23 INFO - PROCESS | 9626 | RSS: Main: 226791424 04:17:23 INFO - PROCESS | 9626 | 04:20:25 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56931/tests/dromaeo/cssquery-prototype.html) 04:20:25 INFO - PROCESS | 9626 | RSS: Main: 227356672 04:20:25 INFO - PROCESS | 9626 | 04:23:28 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56931/tests/dromaeo/cssquery-yui.html) 04:23:29 INFO - PROCESS | 9626 | RSS: Main: 219435008 04:23:29 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | Cycle 1(1): loaded http://localhost:56931/tests/dromaeo/cssquery-yui.html (next: http://localhost:56931/tests/dromaeo/cssquery-dojo.html) 04:26:31 INFO - PROCESS | 9626 | RSS: Main: 257941504 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | __start_tp_report 04:26:31 INFO - PROCESS | 9626 | _x_x_mozilla_page_load 04:26:31 INFO - PROCESS | 9626 | _x_x_mozilla_page_load_details 04:26:31 INFO - PROCESS | 9626 | |i|pagename|runs| 04:26:31 INFO - PROCESS | 9626 | |0;dojo.html;3338.6661333866614;3355.8825441109566;3358.9328213435733;3364.461686130219;3413.948790768139;5509.449055094494;5543.833684989447;5586.329640443142;5608.439156084394;5617;5586.972065139668;5637.915431268524;5646.717664116788;5650.74571644276;5687.971560142193;4894.363732714742;4914.533119353656;5064.265681476193;5092.821751238707;5142.382914050319;4932.950670493285;4941.802327906879;5013.899722005558;5014.047331007097;5020.774065167058;5005.624578156653;5072.4166720827025;5099.209622508517;5140.306058682087;5144.614153938451;5167.534921857032;5195.220716892459;5203.271541984125;5206.739663016872;5237;4893.535114164184;4970.204767237225;5037.168867136918;5087.79648814047;5091.643584949018;5435.728213589345;5467.507924286833;5469.206964990094;5520.558355331564;5523.2543606613;6075.2832840015335;6085.330613632497;6085.442880588815;6110.541709371815;6111;6054.55060620072;6128.625347140861;6129.570930034899;6136.723847426876;6147.692615369258;5719.370869204384;5763.250777398953;5771.018926782457;5773.769049237983;5776.335721392094;5982.312034116046;6258.749650013949;6260.624362538262;6265.4361107500545;6265.65538895356;5831.241938548046;5875.588708790344;5915.290165180119;5987.341392446828;5991.730372133265;6005.099235114768;6006.399360063959;6022.849428764228;6032.879342413127;6039.456448919618;5830.038043722823;5898.115282707542;5978.880422391615;5988.790392336247;5995.940040599626;5946.762129514771;6019.729112189962;6022.638641681513;6028.095785632103;6057.727402266821;4557.248054071041;4561.178987782231;4563.110193512233;4567.771611419482;4590.701604395704;4015.578364271768;4051.9797401012806;4068.389741538793;4106.445629839993;4154.5222299435345;5746.396628354046;5764.971175144097;5767.480926716616;5775.27809023872;5790.420957904176;6027.9095813562835;6099.542534309944;6100.877982440326;6106.175666284995;6128.080787881764;5493.890122197534;5658.434156584309;5681.971590142023;5706.543476521868;5737.5983681141915;5193.714345711022;5196.064708352457;5214.035403450373;5215.374155101412;5222.21666749983;6292.370762923671;6307.400796924193;6314;6315.084312774622;6316.873662526724;5858.326292476249;5957.970210148921;6016.278046634432;6020.127081573235;6086.756529738761;6265.060240963892;6288.308286088618;6288.654124023223;6290.968545157245;6294.213223347082;5679.006173919465;5960.821175364746;5966.164736936746;5967.398937203437;6001.219841420588;5981.730822112929;6004.789832355847;6022.668753218615;6069.787557435468;6073.210482637229;5924.318703348998;5950.702464876652;5965.403459654173;5966.224390829165;5966.761329546943;5677.261955945701;5800.303963524404;5869.35437101927;5914.408559144051;5934.228550288435;5774.711264436677;5803.419658034163;5814.912776308274;5814.970925145347;5841.474267315962;5918.496927761188;5949.91075133882;5950.702464876826;5958.255218097738;5964.105384192406;5606.074997625509;5709.20071190042;5749.770009199585;5780.653160810365;5793.8551536211935 04:26:31 INFO - PROCESS | 9626 | |1;ext.html;608.6378604730183;641.8780431717973;647.0617604473513;658.052404537466;663.8838203314418;35906.461403078945;46569;47603.28595071068;47663.999999999956;51344.22983655239;50728.49271507284;51144.744276278565;51169.48830511703;51192;51281;8350.62422191003;9128.224100951411;9272.211861991738;9275.72172834816;10846.349219046842;10740.24818262722;11133.72165695856;11151.609693660723;11158.665240042812;11160.665180044612;11216.102711783078;11262.943685281562;11263.21157518974;11267.830982535228;11269.098472122252;8523.403361764678;10958.671239862817;11025.724356891062;11295.774084518345;11315.15136364776;10683.626073087482;10964.0680542154;10966.780664386668;10988.230823842334;11098.833517497244;44110.77944610256;44345.55654443479;44640;44644.553554464364;44949.32576011362;3825.7895815730126;3871.7483363581277;4020.3768415895292;4187.099773548702;4441.267190913495;35404;35881.1029724259;35924;36037.81981090104;36285.45571816425;4779.617630589544;4802.927956080662;4824.831130910401;4858.1983972644475;4859.441164266119;373618;375485;375841;376084;376472;41657.37513937293;43011.35482967758;43035.569644303156;69299;69711.65144174246;43065;43145;43208;43215.35176972348;43217.783911080245;16253.10607916576;16438;16442;16452.259648315856;16463.753043704353;12061.155719099579;13117;13511.797323040162;13557.661058473419;13780.966427517977;9682.177014953808;9712.75718107053;9716.805663886682;9762.707118786448;9795.167410770022;3681.613430589803;3687.539057617798;3694.9261014779554;3727.720420968384;3789.886303410902;1036.7356324137447;1043.7025447747508;1138.2658185470325;1186.7092562322287;1250.4560516175493;33355.332893342;33898.49152262718;34025.31949360999;34205;34506.39830910757;1170.2942141529086;1176.3066756896662;1223.7001934526097;1258;1311.731095125497;17353.479395618153;17665.55836104082;17803.821961780475;17820.833873751675;17993.7300940486;550012;931377;947076;947874;950310;66226.66886665535;66288.33711662918;66827;66859.33140668628;66930.33069669339;1416.4546649540002;1417.9503717370048;1433.5126057140415;1516.6132636177704;1517.8026856508584;26630.733692662827;34692;36518;37037.44443833291;37723.24553508969;60940.39059609347;61640.691796540734;61788.69105654443;61957.69021154865;62220.37779622146;364299;364506;364527;366723;367131;30595.08214753561;30815.383692326475;31171;31270.843645781624;31798.046058618278;52749.472505274454;53022.469775303296;53385.7330713344;54139.45860541344;54228.72885635547;45565.31652025288;45844.31233531566;46095.539044609126;46392.30411543897;46506.53493465022;44211.77894110509;44784.77607611941;44851.327230092225;45072.77463612792;45683.086338272384;45104.548954510035;45272.32091518696;45673.77163114163;46181;46410.7679461614;36653.81673091617;39572.40641390324;40030.59969400269;42158.578414215466;42520.5747942529;29603.40793184081;29685;29833.55249671213;30192.396152077275;30344.393112137193 04:26:31 INFO - PROCESS | 9626 | |2;jquery.html;434.6327353386387;435.26874850251573;444.1006960904169;444.78205679217183;449.6582597226107;18951.33670321539;19887.701684474745;20376.694349584748;20933.267335643257;20937.47656308593;19361.22555097798;19675.31136410226;19943.202271909107;19963;19985.800141998574;5740.856478588048;5978.372270911557;6011.30869949957;6020.729067191987;6030.66831324277;5875.618084824473;5878.235829342179;5960.5529585281065;5968.7612495500125;5973;5709.800156994506;5854.268216472941;5856.355800861902;5857.297124345085;5873.148393482942;5809.186713860042;5913.793017244398;5922.496587790043;5931.851703707399;5932.6143800653035;5101.438841727407;5116.513931176532;5256.159014557691;5257.342832145981;5292.417834038252;23755.287341379786;23888;23940.521189576284;24030.519389612284;24105.397365065837;2298.402415371999;2466.962995555068;2615.4115324052127;2779.8471084090365;2814.451182019507;13960.93019534906;14211.36048877803;14535.636609084857;14909.776353354708;15067.924660376628;2046.4883779055235;2107.1255428996947;2107.2308607358204;2120.862143960653;2146.8175205107586;77037.61481192615;79407.20592794113;79571.6021419895;80383;81300.59349703272;10889.673309800719;10996.670099897015;11161.218714690056;11244.775104498029;11408.828867566994;15523;15602.141882196589;15607.3757049719;15618.9219053904;15660.825438092188;8172;8329.416940814084;8388.622511986976;8421.989361276686;8462.984441866893;8034.397420193509;8102.635381407733;8137.511749295061;8218.958905205554;8284.751457456287;6801.65991700413;6877.89683154753;6908.5509441886115;6954.478414118961;6990.300969902969;2013.1242909334487;2059.979400206009;2073.7407824022;2076.5535409886947;2112.186808078901;1046.947652617366;1104.828751543512;1121.1367247219596;1129.7006293332274;1145.7422080031927;18331.17509712066;19814.90092549528;19935.20259189645;20297.492562685755;20330.085146168458;1040.4485622620023;1101.4052411697753;1107.241539545414;1109.8446217529552;1132.1565433751744;10927.890721092688;11107.555697771999;11527.942360288145;11582.131340149523;11624.709382265342;471316;478019;503496;504734;509532;72279.63860180666;84229.57885210535;87567;91171;93744;1080.6109800471825;1081.1350919264557;1106.6790630717323;1108.7616162524935;1113.3097479562723;17364.565885852953;17539;17594.472165835046;17743.911280443514;17947;39893.800530997156;41322;41563.79218104011;41737.79131104325;42876;56254.43745562492;58215.417845822696;58244.4175558239;58463.70768146132;58700;7515.511491753237;7925.920740792519;8267.29727973129;8538.78653033679;8644.265237454887;12564.560240391542;12661.493540258287;12880.742385152433;12915.160514566523;12960;11208.607698730506;11492.54029838797;11499.827502587636;11526.59656912004;11558.30650160993;11538.44231028834;11541.422928853355;11542.538298467967;11543.4805433754;11632.651020469399;11502.769944601228;11661.591844285409;11789.70525736829;11917.702057448632;11923.344216068199;8010.439269251096;8014;8101.230383113595;8175.7138500152205;8189.672413103409;11333.659990200307;11485.885141148481;11557.711057223636;11594.5362185515;11908.345041022827 04:26:31 INFO - PROCESS | 9626 | |3;mootools.html;1427.643089227693;1576.9211539423027;1596.7924169857918;1787.9374221902235;1923;8579.656813727452;9503.287253455992;9768.804623907517;9781.657641982523;10011.449370284632;9612.327137100407;9707;9795.804083918318;9820.067093626112;9822.116009559139;2283.4177284792354;2521.9495610087833;2526.3305224115625;2556.041484443334;2597.571400718879;2600.7399260073935;2605.661264035682;2621.436391175897;2624.7768939640155;2627.435101453187;2591.3651155466937;2598.519273934319;2602.908898188564;2603.778678812303;2605.544029794788;2240.764719704432;2479.925602231936;2547.9363015924564;2551.413174969749;2566.268613445159;2482.453860150764;2497.662815519918;2503.0988844016138;2514.0320976424027;2514.798816094708;9809.852852207223;9860.211183105333;9878.160356369717;9886.90113098867;9890.20878329732;5901.17383566301;6053.1828203192445;6055.12200730896;6056.878862422727;6060.666663333515;3740.4950331705145;3777.452269420946;3875.9224815503812;3927.1360300734077;3930.5872883347124;1232.106722626098;1265.6645988812973;1290.6837824732906;1320.920744755308;1367.09771550777;14355.85644143566;14366.56900292993;14390.064645798095;14402.351894164787;14407.207603581799;5253.185756207794;5260.631755777135;5319.0957537218765;5323.574114070942;5386.380566234933;11852.170348075551;11852.407379631157;11858;11872.10959178065;11887.049036077093;5304.522592966651;5902.675352855548;5990.430909063632;5997.040473524303;6003.3996600339615;5294.152935530374;5440.809571665052;5442.346918369742;5489.505944465017;5494.066008778517;4492.573205545468;4538.478075021349;4549.317602359606;4721.8347357842995;4750.192467280571;1380.523719316834;1425.736238795826;1425.964350891236;1437.5543581489771;1454.120257244373;925.9120383563551;1008.6620981970975;1010.6563768318807;1016.9084782369474;1037.1339931157418;6210.813675589739;6337.6197428154455;6339.841503962438;6348.746050158034;6453.290138084807;433.1877729257642;452.1409322287614;456.27680126998416;456.77161419290354;466.37272867992493;4168.645665118438;4427.977860110679;4537.886552836206;4622.607078398374;4741.051789642085;12423.068269879795;12441.813372799415;12638.684032899251;12672.619821405373;12693.365331733561;12423.503059877685;12507.93746031264;12539.435725392379;12563.685907852378;12565.623031309075;9067.501287429122;9146.359754817233;9160.770980725536;9238.353315267866;9275.860862087075;6433.742650294123;6493.675316234264;6517.27292267559;6572.047053177167;6572.277049524453;11065.19742883374;11382.772344553228;11428.82856757166;11640.126990475917;11663;11260.83108753353;11633.53465861356;11698.883011169779;11782.234154779911;11931.642050738492;8051.275385215359;8606.569671516274;8880.644774209217;8898;9077.228435582785;9685.70942871715;9735.756606084904;9764.121229089416;9803.803923921625;9809.656662016796;8512.796038895196;8739.257163141205;8795.120487951153;8832;8833.779155521164;8170.223828736261;8193.836123277382;8418.873716894374;8448.83102337962;8474.449160804528;7346.485745997729;7413.406927445899;7415.2214017527385;7424.851502969802;7442.590657513889;5382.811601593926;5455.781768729206;5557.555395568425;5658.066419040977;5744.885102298014;5810.854728631818;5827.24245848037;5851.297844258716;5868.501177399969;5986.311574169026 04:26:31 INFO - PROCESS | 9626 | |4;prototype.html;358.17619475207016;359.2653024564765;363.35867194401885;369.23752451188295;370.73492452896176;5513.4210907854595;5532.6957017364075;5533.47431993961;5580.6930618815995;5588.273524441827;5193.974030129853;5435.945640543603;5457.863553411166;5462.235287059815;5473.397926228122;4231.492220933493;4279.978600106995;4281.736887618153;4291.506476755182;4293.65650747941;4228.513720922103;4259.91480170398;4288.399624052635;4295.398644189816;4299.247631664446;4240.512341080785;4248.2140803951215;4255.5318914919335;4289.828406863721;4306.97846510767;4132;4209.97895010526;4234.724742891703;4236.428082208924;4260.360945858115;3652.9634703652887;3710.3877860153043;3722.8510859565586;3737.495438115847;3759.586445490994;5377.489138531833;5378.784848606051;5388.218708287315;5402.540784033323;5431.6740995540395;4780.545848144421;4871.610271178297;4874.975625121887;4885.5847252983185;4929.866130789938;5709.745445096297;5738.7991420300505;5740.54075673945;5750.511206547407;5759.510441612468;4123.752574845519;4132.504099508048;4142.855000075013;4157.792110394468;4173.269677806372;5749.770009199627;5839.766409343663;5860.824175274749;5988.490978266853;6087.54343424245;4281.892952676146;4282.164977829293;4293.218314399552;4313.870583882489;4324.762138082373;4321.546237645065;4372.40972468719;4376.352306854371;4378.146261478981;4395.340698895127;4694.507076756891;4788.63523895688;4898.657094003385;4918.851934761756;4980.77586508608;5784.814113106803;5800.56495762811;5804.564657650693;5813.302403711497;5818;1494.880409567232;1508.7133444645483;1515.3559737111684;1524.8170219573722;1530.143119852886;949.3212353167519;976.2970661123979;976.8437050071955;982.2731178927543;987.8073775613686;4992.600591952635;4995.700257984533;5014.323066385991;5032.597392208614;5101.7194054327365;5256.6057545684225;5267.920981185285;5280.683159010472;5293.894122117536;5331.226972089103;4845.207273308898;4958.57852082577;5006.873453473012;5010.574101201366;5035.899282014412;2794.3433293176085;2821.5767634854524;2826.5618829081113;2848.7293707097792;2898.318895059659;5272.340957380327;5392.541633961078;5452.345718513724;5458.972705136449;5471.37079235893;5183.896322073613;5397.136458166753;5409.891802163935;5425.430329815313;5438.401775804658;5422.593305502103;5426.430224826494;5483.945160548423;5485.4788795064405;5537.8061767837935;4879.975600121977;5301.840944771663;5323.467653234646;5347.518723314764;5379.300690910157;5220.504052115043;5266.578673706018;5319.4732502061215;5331.573474122139;5334.866628334323;6015.729292181775;6047.455728984412;6143.389059649542;6204.348543402878;6211.353991192247;4198.202341555095;4212.789360532022;4213.683973701924;4218.489998450917;4227.682923780667;5854.970725146347;5995.700214989321;5997.190379298913;6061.030235162353;6110.602810817282;5867.647941123546;5885.882282354414;5885.970570147122;5902.498287645598;5906.852328691817;5788.189653448605;5819.5053420459735;5823.903394777792;5834.124881267844;5839.708014599338;5630.690312032878;5666.688332141772;5806.332271788799;5807.315878395218;5812.21535092774;5905.970470147622;5910.881782364415;5916.3492015877355;5934.910976335272;5936.13925980739;5471.2887324647545;5674.233978412862;5799;5809.419058094157;5813.215215945962 04:26:31 INFO - PROCESS | 9626 | |5;yui.html;795.8806179073142;817.2236375443331;879.6261588824748;881.03526638331;915.7069737683939;14731.705365892676;14829.703405931876;14842.554723358304;14852.33164507597;14876.586724261191;13209.669758256048;13591.184528928246;13769;13776.862231377683;13840.930795346034;860.8450478913793;877.4296707140346;878.1569693094636;880.2561835249236;880.828238493494;730.0399974034154;850.8893843800329;873.5108339329964;873.9257163141141;875.6366108065146;912.2428384440898;914.4878867834034;916.3997581584073;917.0829170829171;918.0085507651753;758.4614923404389;910.5037754423855;911.9954400227956;912.3795818843184;913.5888850017519;654.1398061549095;674.4671709349608;677.5189615373091;678.9762358317436;679.239252037716;13627.454901803916;13686.247256400893;13698.65753356174;13705.040647154703;13706;14248.786268205986;14371.353289102015;14434.855651443457;14655.193964331958;14695.559133225914;12924.224546527144;13028;13508.797368039488;13549.322533873266;13895.374708138057;4576.725396476222;4603.769811509411;4612.331211974245;4642.117997580449;4662.067586482717;70515.2948470519;70581.64709176472;70609.29390706078;70975;74182.99999999946;11033;11233.438328083563;11361.772764544829;11373.147013973985;11846.526138954345;20641.793582063987;21166.153353865982;21366.572668546854;21368.358949231548;21694;8166.959165204136;8186.713465028695;8371.246587807005;8858.923017300473;8891.911080889238;4697.6241900647865;4766.594839438687;4788.569028787356;4862.027594481118;4935.8025678972435;3605.837737301828;3728.440733889884;3731.7014638828828;3735.0662334416397;3738.1962877981364;595.1994567306965;661.3089321658878;663.0915645565606;664.5248647217167;666.7732970789955;727.4943913979839;729.0886392009987;765.4374035084209;765.9425543084291;769.415244414238;10135.189184865194;10185.388876667424;10382.532786024496;10772.94613526943;10825.67522974312;714.731975509184;718.2027948976631;724.5037149552574;725.3073314984177;731.6305265840716;4559.54404559548;4757.333973243749;4826.8793280168275;4878.82924097662;4879.219324907997;111505;113267;116818.41590791992;117511.8248817518;118154.4092279533;100556.49721751345;100622;100751;101390;102086;86154.56922715346;87151;89097.10902890888;89123;90060.99999999869;9267.72196834096;9300.255979521757;9391.577379017825;9442.575084121368;9494.810103798023;54634.726826365615;55055.72472137614;55847.72076139593;56320.43679563152;56923;58620;79675;80476.59761701155;80502.19497804948;80668;10766.138708903425;10974.122070234522;11570.826437603611;11679.06567474617;12025.518979240733;11870.10860884866;12151.392430378623;12683.936580317408;13426.798598020841;13676.589702308947;11319.490622922152;11440.599579014695;11509.366984815915;14383.352749126523;15008.174550399834;12571.685707857378;12604.936975315064;12637.494500219887;12733.745325093261;13843.100198487065;12598.748025039264;13105.344732763133;13153;13547.729045418839;13954.23251721165;11074.833877491683;11091.667249982513;11541.36522491271;12128.211666241667;12338.75322493563;11149.129176323197;11268.830967535658;11280.153988450735;11405.942970285096;11496.655100347003 04:26:31 INFO - PROCESS | 9626 | __end_tp_report 04:26:31 INFO - PROCESS | 9626 | __start_cc_report 04:26:31 INFO - PROCESS | 9626 | _x_x_mozilla_cycle_collect,903 04:26:31 INFO - PROCESS | 9626 | __end_cc_report 04:26:31 INFO - PROCESS | 9626 | __startTimestamp1467545191572__endTimestamp 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | ------- Summary: start ------- 04:26:31 INFO - PROCESS | 9626 | Number of tests: 6 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#0] dojo.html Cycles:165 Average:5595.07 Median:5793.86 stddev:644.42 (11.1%) stddev-sans-first:621.61 04:26:31 INFO - PROCESS | 9626 | Values: 3338.7 3355.9 3358.9 3364.5 3413.9 5509.4 5543.8 5586.3 5608.4 5617.0 5587.0 5637.9 5646.7 5650.7 5688.0 4894.4 4914.5 5064.3 5092.8 5142.4 4933.0 4941.8 5013.9 5014.0 5020.8 5005.6 5072.4 5099.2 5140.3 5144.6 5167.5 5195.2 5203.3 5206.7 5237.0 4893.5 4970.2 5037.2 5087.8 5091.6 5435.7 5467.5 5469.2 5520.6 5523.3 6075.3 6085.3 6085.4 6110.5 6111.0 6054.6 6128.6 6129.6 6136.7 6147.7 5719.4 5763.3 5771.0 5773.8 5776.3 5982.3 6258.7 6260.6 6265.4 6265.7 5831.2 5875.6 5915.3 5987.3 5991.7 6005.1 6006.4 6022.8 6032.9 6039.5 5830.0 5898.1 5978.9 5988.8 5995.9 5946.8 6019.7 6022.6 6028.1 6057.7 4557.2 4561.2 4563.1 4567.8 4590.7 4015.6 4052.0 4068.4 4106.4 4154.5 5746.4 5765.0 5767.5 5775.3 5790.4 6027.9 6099.5 6100.9 6106.2 6128.1 5493.9 5658.4 5682.0 5706.5 5737.6 5193.7 5196.1 5214.0 5215.4 5222.2 6292.4 6307.4 6314.0 6315.1 6316.9 5858.3 5958.0 6016.3 6020.1 6086.8 6265.1 6288.3 6288.7 6291.0 6294.2 5679.0 5960.8 5966.2 5967.4 6001.2 5981.7 6004.8 6022.7 6069.8 6073.2 5924.3 5950.7 5965.4 5966.2 5966.8 5677.3 5800.3 5869.4 5914.4 5934.2 5774.7 5803.4 5814.9 5815.0 5841.5 5918.5 5949.9 5950.7 5958.3 5964.1 5606.1 5709.2 5749.8 5780.7 5793.9 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#1] ext.html Cycles:180 Average:69330.51 Median:20756.57 stddev:159676.06 (769.3%) stddev-sans-first:160040.62 04:26:31 INFO - PROCESS | 9626 | Values: 608.6 641.9 647.1 658.1 663.9 35906.5 46569.0 47603.3 47664.0 51344.2 50728.5 51144.7 51169.5 51192.0 51281.0 8350.6 9128.2 9272.2 9275.7 10846.3 10740.2 11133.7 11151.6 11158.7 11160.7 11216.1 11262.9 11263.2 11267.8 11269.1 8523.4 10958.7 11025.7 11295.8 11315.2 10683.6 10964.1 10966.8 10988.2 11098.8 44110.8 44345.6 44640.0 44644.6 44949.3 3825.8 3871.7 4020.4 4187.1 4441.3 35404.0 35881.1 35924.0 36037.8 36285.5 4779.6 4802.9 4824.8 4858.2 4859.4 373618.0 375485.0 375841.0 376084.0 376472.0 41657.4 43011.4 43035.6 69299.0 69711.7 43065.0 43145.0 43208.0 43215.4 43217.8 16253.1 16438.0 16442.0 16452.3 16463.8 12061.2 13117.0 13511.8 13557.7 13781.0 9682.2 9712.8 9716.8 9762.7 9795.2 3681.6 3687.5 3694.9 3727.7 3789.9 1036.7 1043.7 1138.3 1186.7 1250.5 33355.3 33898.5 34025.3 34205.0 34506.4 1170.3 1176.3 1223.7 1258.0 1311.7 17353.5 17665.6 17803.8 17820.8 17993.7 550012.0 931377.0 947076.0 947874.0 950310.0 66226.7 66288.3 66827.0 66859.3 66930.3 1416.5 1418.0 1433.5 1516.6 1517.8 26630.7 34692.0 36518.0 37037.4 37723.2 60940.4 61640.7 61788.7 61957.7 62220.4 364299.0 364506.0 364527.0 366723.0 367131.0 30595.1 30815.4 31171.0 31270.8 31798.0 52749.5 53022.5 53385.7 54139.5 54228.7 45565.3 45844.3 46095.5 46392.3 46506.5 44211.8 44784.8 44851.3 45072.8 45683.1 45104.5 45272.3 45673.8 46181.0 46410.8 36653.8 39572.4 40030.6 42158.6 42520.6 29603.4 29685.0 29833.6 30192.4 30344.4 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#2] jquery.html Cycles:180 Average:29176.51 Median:13026.84 stddev:81171.01 (623.1%) stddev-sans-first:81370.03 04:26:31 INFO - PROCESS | 9626 | Values: 434.6 435.3 444.1 444.8 449.7 18951.3 19887.7 20376.7 20933.3 20937.5 19361.2 19675.3 19943.2 19963.0 19985.8 5740.9 5978.4 6011.3 6020.7 6030.7 5875.6 5878.2 5960.6 5968.8 5973.0 5709.8 5854.3 5856.4 5857.3 5873.1 5809.2 5913.8 5922.5 5931.9 5932.6 5101.4 5116.5 5256.2 5257.3 5292.4 23755.3 23888.0 23940.5 24030.5 24105.4 2298.4 2467.0 2615.4 2779.8 2814.5 13960.9 14211.4 14535.6 14909.8 15067.9 2046.5 2107.1 2107.2 2120.9 2146.8 77037.6 79407.2 79571.6 80383.0 81300.6 10889.7 10996.7 11161.2 11244.8 11408.8 15523.0 15602.1 15607.4 15618.9 15660.8 8172.0 8329.4 8388.6 8422.0 8463.0 8034.4 8102.6 8137.5 8219.0 8284.8 6801.7 6877.9 6908.6 6954.5 6990.3 2013.1 2060.0 2073.7 2076.6 2112.2 1046.9 1104.8 1121.1 1129.7 1145.7 18331.2 19814.9 19935.2 20297.5 20330.1 1040.4 1101.4 1107.2 1109.8 1132.2 10927.9 11107.6 11527.9 11582.1 11624.7 471316.0 478019.0 503496.0 504734.0 509532.0 72279.6 84229.6 87567.0 91171.0 93744.0 1080.6 1081.1 1106.7 1108.8 1113.3 17364.6 17539.0 17594.5 17743.9 17947.0 39893.8 41322.0 41563.8 41737.8 42876.0 56254.4 58215.4 58244.4 58463.7 58700.0 7515.5 7925.9 8267.3 8538.8 8644.3 12564.6 12661.5 12880.7 12915.2 12960.0 11208.6 11492.5 11499.8 11526.6 11558.3 11538.4 11541.4 11542.5 11543.5 11632.7 11502.8 11661.6 11789.7 11917.7 11923.3 8010.4 8014.0 8101.2 8175.7 8189.7 11333.7 11485.9 11557.7 11594.5 11908.3 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#3] mootools.html Cycles:180 Average:6502.71 Median:2544.49 stddev:3809.29 (149.7%) stddev-sans-first:3800.88 04:26:31 INFO - PROCESS | 9626 | Values: 1427.6 1576.9 1596.8 1787.9 1923.0 8579.7 9503.3 9768.8 9781.7 10011.4 9612.3 9707.0 9795.8 9820.1 9822.1 2283.4 2521.9 2526.3 2556.0 2597.6 2600.7 2605.7 2621.4 2624.8 2627.4 2591.4 2598.5 2602.9 2603.8 2605.5 2240.8 2479.9 2547.9 2551.4 2566.3 2482.5 2497.7 2503.1 2514.0 2514.8 9809.9 9860.2 9878.2 9886.9 9890.2 5901.2 6053.2 6055.1 6056.9 6060.7 3740.5 3777.5 3875.9 3927.1 3930.6 1232.1 1265.7 1290.7 1320.9 1367.1 14355.9 14366.6 14390.1 14402.4 14407.2 5253.2 5260.6 5319.1 5323.6 5386.4 11852.2 11852.4 11858.0 11872.1 11887.0 5304.5 5902.7 5990.4 5997.0 6003.4 5294.2 5440.8 5442.3 5489.5 5494.1 4492.6 4538.5 4549.3 4721.8 4750.2 1380.5 1425.7 1426.0 1437.6 1454.1 925.9 1008.7 1010.7 1016.9 1037.1 6210.8 6337.6 6339.8 6348.7 6453.3 433.2 452.1 456.3 456.8 466.4 4168.6 4428.0 4537.9 4622.6 4741.1 12423.1 12441.8 12638.7 12672.6 12693.4 12423.5 12507.9 12539.4 12563.7 12565.6 9067.5 9146.4 9160.8 9238.4 9275.9 6433.7 6493.7 6517.3 6572.0 6572.3 11065.2 11382.8 11428.8 11640.1 11663.0 11260.8 11633.5 11698.9 11782.2 11931.6 8051.3 8606.6 8880.6 8898.0 9077.2 9685.7 9735.8 9764.1 9803.8 9809.7 8512.8 8739.3 8795.1 8832.0 8833.8 8170.2 8193.8 8418.9 8448.8 8474.4 7346.5 7413.4 7415.2 7424.9 7442.6 5382.8 5455.8 5557.6 5658.1 5744.9 5810.9 5827.2 5851.3 5868.5 5986.3 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#4] prototype.html Cycles:180 Average:4730.25 Median:5310.66 stddev:1370.19 (25.8%) stddev-sans-first:1334.16 04:26:31 INFO - PROCESS | 9626 | Values: 358.2 359.3 363.4 369.2 370.7 5513.4 5532.7 5533.5 5580.7 5588.3 5194.0 5435.9 5457.9 5462.2 5473.4 4231.5 4280.0 4281.7 4291.5 4293.7 4228.5 4259.9 4288.4 4295.4 4299.2 4240.5 4248.2 4255.5 4289.8 4307.0 4132.0 4210.0 4234.7 4236.4 4260.4 3653.0 3710.4 3722.9 3737.5 3759.6 5377.5 5378.8 5388.2 5402.5 5431.7 4780.5 4871.6 4875.0 4885.6 4929.9 5709.7 5738.8 5740.5 5750.5 5759.5 4123.8 4132.5 4142.9 4157.8 4173.3 5749.8 5839.8 5860.8 5988.5 6087.5 4281.9 4282.2 4293.2 4313.9 4324.8 4321.5 4372.4 4376.4 4378.1 4395.3 4694.5 4788.6 4898.7 4918.9 4980.8 5784.8 5800.6 5804.6 5813.3 5818.0 1494.9 1508.7 1515.4 1524.8 1530.1 949.3 976.3 976.8 982.3 987.8 4992.6 4995.7 5014.3 5032.6 5101.7 5256.6 5267.9 5280.7 5293.9 5331.2 4845.2 4958.6 5006.9 5010.6 5035.9 2794.3 2821.6 2826.6 2848.7 2898.3 5272.3 5392.5 5452.3 5459.0 5471.4 5183.9 5397.1 5409.9 5425.4 5438.4 5422.6 5426.4 5483.9 5485.5 5537.8 4880.0 5301.8 5323.5 5347.5 5379.3 5220.5 5266.6 5319.5 5331.6 5334.9 6015.7 6047.5 6143.4 6204.3 6211.4 4198.2 4212.8 4213.7 4218.5 4227.7 5855.0 5995.7 5997.2 6061.0 6110.6 5867.6 5885.9 5886.0 5902.5 5906.9 5788.2 5819.5 5823.9 5834.1 5839.7 5630.7 5666.7 5806.3 5807.3 5812.2 5906.0 5910.9 5916.3 5934.9 5936.1 5471.3 5674.2 5799.0 5809.4 5813.2 04:26:31 INFO - PROCESS | 9626 | 04:26:31 INFO - PROCESS | 9626 | [#5] yui.html Cycles:180 Average:20833.86 Median:4568.13 stddev:30254.60 (662.3%) stddev-sans-first:30302.06 04:26:31 INFO - PROCESS | 9626 | Values: 795.9 817.2 879.6 881.0 915.7 14731.7 14829.7 14842.6 14852.3 14876.6 13209.7 13591.2 13769.0 13776.9 13840.9 860.8 877.4 878.2 880.3 880.8 730.0 850.9 873.5 873.9 875.6 912.2 914.5 916.4 917.1 918.0 758.5 910.5 912.0 912.4 913.6 654.1 674.5 677.5 679.0 679.2 13627.5 13686.2 13698.7 13705.0 13706.0 14248.8 14371.4 14434.9 14655.2 14695.6 12924.2 13028.0 13508.8 13549.3 13895.4 4576.7 4603.8 4612.3 4642.1 4662.1 70515.3 70581.6 70609.3 70975.0 74183.0 11033.0 11233.4 11361.8 11373.1 11846.5 20641.8 21166.2 21366.6 21368.4 21694.0 8167.0 8186.7 8371.2 8858.9 8891.9 4697.6 4766.6 4788.6 4862.0 4935.8 3605.8 3728.4 3731.7 3735.1 3738.2 595.2 661.3 663.1 664.5 666.8 727.5 729.1 765.4 765.9 769.4 10135.2 10185.4 10382.5 10772.9 10825.7 714.7 718.2 724.5 725.3 731.6 4559.5 4757.3 4826.9 4878.8 4879.2 111505.0 113267.0 116818.4 117511.8 118154.4 100556.5 100622.0 100751.0 101390.0 102086.0 86154.6 87151.0 89097.1 89123.0 90061.0 9267.7 9300.3 9391.6 9442.6 9494.8 54634.7 55055.7 55847.7 56320.4 56923.0 58620.0 79675.0 80476.6 80502.2 80668.0 10766.1 10974.1 11570.8 11679.1 12025.5 11870.1 12151.4 12683.9 13426.8 13676.6 11319.5 11440.6 11509.4 14383.4 15008.2 12571.7 12604.9 12637.5 12733.7 13843.1 12598.7 13105.3 13153.0 13547.7 13954.2 11074.8 11091.7 11541.4 12128.2 12338.8 11149.1 11268.8 11280.2 11405.9 11496.7 04:26:31 INFO - PROCESS | 9626 | -------- Summary: end -------- 04:26:31 INFO - PROCESS | 9626 | 04:26:32 INFO - TEST-INFO | 9626: exit 0 04:26:32 INFO - TEST-OK | dromaeo_css | took 1112429ms 04:26:32 INFO - TEST-START | kraken 04:26:32 INFO - operating with platform_type : linux_ 04:26:32 INFO - Initialising browser for kraken test... 04:26:32 INFO - TEST-INFO | started process 9821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCRdalT/profile http://localhost:56931/getInfo.html) 04:26:35 INFO - PROCESS | 9821 | __metrics Screen width/height:1600/1200 04:26:35 INFO - PROCESS | 9821 | colorDepth:24 04:26:35 INFO - PROCESS | 9821 | Browser inner width/height: 1024/697 04:26:35 INFO - PROCESS | 9821 | __metrics 04:26:36 ERROR - PROCESS | 9821 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:26:37 INFO - TEST-INFO | 9821: exit 0 04:26:37 INFO - Browser initialized. 04:26:37 INFO - Running cycle 1/1 for kraken test... 04:26:37 INFO - TEST-INFO | started process 9911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCRdalT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:49 INFO - PROCESS | 9911 | RSS: Main: 149151744 04:26:49 INFO - PROCESS | 9911 | 04:26:51 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56931/tests/kraken/driver.html?testName=audio-beat-detection) 04:26:52 INFO - PROCESS | 9911 | RSS: Main: 198520832 04:26:52 INFO - PROCESS | 9911 | 04:26:55 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56931/tests/kraken/driver.html?testName=audio-dft) 04:26:55 INFO - PROCESS | 9911 | RSS: Main: 196911104 04:26:55 INFO - PROCESS | 9911 | 04:26:58 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56931/tests/kraken/driver.html?testName=audio-fft) 04:26:59 INFO - PROCESS | 9911 | RSS: Main: 282800128 04:26:59 INFO - PROCESS | 9911 | 04:27:01 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56931/tests/kraken/driver.html?testName=audio-oscillator) 04:27:01 INFO - PROCESS | 9911 | RSS: Main: 190726144 04:27:01 INFO - PROCESS | 9911 | 04:27:04 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56931/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:27:04 INFO - PROCESS | 9911 | RSS: Main: 191852544 04:27:04 INFO - PROCESS | 9911 | 04:27:07 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56931/tests/kraken/driver.html?testName=imaging-darkroom) 04:27:08 INFO - PROCESS | 9911 | RSS: Main: 226447360 04:27:08 INFO - PROCESS | 9911 | 04:27:11 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56931/tests/kraken/driver.html?testName=imaging-desaturate) 04:27:12 INFO - PROCESS | 9911 | RSS: Main: 227627008 04:27:12 INFO - PROCESS | 9911 | 04:27:15 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56931/tests/kraken/driver.html?testName=json-parse-financial) 04:27:15 INFO - PROCESS | 9911 | RSS: Main: 227119104 04:27:15 INFO - PROCESS | 9911 | 04:27:17 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56931/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:27:17 INFO - PROCESS | 9911 | RSS: Main: 205295616 04:27:17 INFO - PROCESS | 9911 | 04:27:19 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:27:19 INFO - PROCESS | 9911 | RSS: Main: 206725120 04:27:19 INFO - PROCESS | 9911 | 04:27:22 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:27:22 INFO - PROCESS | 9911 | RSS: Main: 209281024 04:27:22 INFO - PROCESS | 9911 | 04:27:25 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:27:25 INFO - PROCESS | 9911 | RSS: Main: 215691264 04:27:25 INFO - PROCESS | 9911 | 04:27:28 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:27:28 INFO - PROCESS | 9911 | RSS: Main: 213262336 04:27:28 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | Cycle 1(1): loaded http://localhost:56931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56931/tests/kraken/driver.html?testName=ai-astar) 04:27:30 INFO - PROCESS | 9911 | RSS: Main: 256086016 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | __start_tp_report 04:27:30 INFO - PROCESS | 9911 | _x_x_mozilla_page_load 04:27:30 INFO - PROCESS | 9911 | _x_x_mozilla_page_load_details 04:27:30 INFO - PROCESS | 9911 | |i|pagename|runs| 04:27:30 INFO - PROCESS | 9911 | |0;ai-astar;110;104;109;109;108;108;108;110;109;109 04:27:30 INFO - PROCESS | 9911 | |1;audio-beat-detection;156;124;124;167;123;125;155;123;160;124 04:27:30 INFO - PROCESS | 9911 | |2;audio-dft;160;161;161;160;161;159;161;161;159;159 04:27:30 INFO - PROCESS | 9911 | |3;audio-fft;76;75;76;122;76;76;84;76;97;75 04:27:30 INFO - PROCESS | 9911 | |4;audio-oscillator;85;85;116;85;84;85;123;84;84;137 04:27:30 INFO - PROCESS | 9911 | |5;imaging-gaussian-blur;116;117;117;117;116;117;116;117;117;117 04:27:30 INFO - PROCESS | 9911 | |6;imaging-darkroom;146;145;146;145;145;145;145;145;145;145 04:27:30 INFO - PROCESS | 9911 | |7;imaging-desaturate;95;95;96;95;95;94;95;95;95;95 04:27:30 INFO - PROCESS | 9911 | |8;json-parse-financial;50;50;48;51;51;49;50;50;53;50 04:27:30 INFO - PROCESS | 9911 | |9;json-stringify-tinderbox;59;66;59;59;60;59;60;59;59;60 04:27:30 INFO - PROCESS | 9911 | |10;stanford-crypto-aes;76;76;75;83;77;77;77;77;117;79 04:27:30 INFO - PROCESS | 9911 | |11;stanford-crypto-ccm;124;126;117;119;124;118;126;125;133;118 04:27:30 INFO - PROCESS | 9911 | |12;stanford-crypto-pbkdf2;151;150;151;155;152;153;154;155;156;156 04:27:30 INFO - PROCESS | 9911 | |13;stanford-crypto-sha256-iterative;63;60;60;60;61;61;61;61;61;60 04:27:30 INFO - PROCESS | 9911 | __end_tp_report 04:27:30 INFO - PROCESS | 9911 | __start_cc_report 04:27:30 INFO - PROCESS | 9911 | _x_x_mozilla_cycle_collect,1569 04:27:30 INFO - PROCESS | 9911 | __end_cc_report 04:27:30 INFO - PROCESS | 9911 | __startTimestamp1467545250888__endTimestamp 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | ------- Summary: start ------- 04:27:30 INFO - PROCESS | 9911 | Number of tests: 14 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:1.71 (1.6%) stddev-sans-first:1.72 04:27:30 INFO - PROCESS | 9911 | Values: 110.0 104.0 109.0 109.0 108.0 108.0 108.0 110.0 109.0 109.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:140.00 stddev:18.69 (13.4%) stddev-sans-first:18.67 04:27:30 INFO - PROCESS | 9911 | Values: 156.0 124.0 124.0 167.0 123.0 125.0 155.0 123.0 160.0 124.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 04:27:30 INFO - PROCESS | 9911 | Values: 160.0 161.0 161.0 160.0 161.0 159.0 161.0 161.0 159.0 159.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.00 stddev:15.24 (20.1%) stddev-sans-first:15.93 04:27:30 INFO - PROCESS | 9911 | Values: 76.0 75.0 76.0 122.0 76.0 76.0 84.0 76.0 97.0 75.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:84.50 stddev:20.33 (24.1%) stddev-sans-first:21.11 04:27:30 INFO - PROCESS | 9911 | Values: 85.0 85.0 116.0 85.0 84.0 85.0 123.0 84.0 84.0 137.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:27:30 INFO - PROCESS | 9911 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 04:27:30 INFO - PROCESS | 9911 | Values: 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 04:27:30 INFO - PROCESS | 9911 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:1.32 (2.6%) stddev-sans-first:1.39 04:27:30 INFO - PROCESS | 9911 | Values: 50.0 50.0 48.0 51.0 51.0 49.0 50.0 50.0 53.0 50.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.50 stddev:2.16 (3.6%) stddev-sans-first:2.26 04:27:30 INFO - PROCESS | 9911 | Values: 59.0 66.0 59.0 59.0 60.0 59.0 60.0 59.0 59.0 60.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:77.00 stddev:12.70 (16.5%) stddev-sans-first:13.32 04:27:30 INFO - PROCESS | 9911 | Values: 76.0 76.0 75.0 83.0 77.0 77.0 77.0 77.0 117.0 79.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:124.50 stddev:5.01 (4.0%) stddev-sans-first:5.30 04:27:30 INFO - PROCESS | 9911 | Values: 124.0 126.0 117.0 119.0 124.0 118.0 126.0 125.0 133.0 118.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:154.50 stddev:2.21 (1.4%) stddev-sans-first:2.19 04:27:30 INFO - PROCESS | 9911 | Values: 151.0 150.0 151.0 155.0 152.0 153.0 154.0 155.0 156.0 156.0 04:27:30 INFO - PROCESS | 9911 | 04:27:30 INFO - PROCESS | 9911 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.53 04:27:30 INFO - PROCESS | 9911 | Values: 63.0 60.0 60.0 60.0 61.0 61.0 61.0 61.0 61.0 60.0 04:27:30 INFO - PROCESS | 9911 | -------- Summary: end -------- 04:27:30 INFO - PROCESS | 9911 | 04:27:31 INFO - TEST-INFO | 9911: exit 0 04:27:31 INFO - TEST-OK | kraken | took 59525ms 04:27:31 INFO - SUITE-END | took 1171s 04:27:32 INFO - Completed test suite (00:19:32) 04:27:32 INFO - javascript benchmark 04:27:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.6661333866614, 3355.8825441109566, 3358.9328213435733, 3364.461686130219, 3413.948790768139, 5509.449055094494, 5543.833684989447, 5586.329640443142, 5608.439156084394, 5617.0, 5586.972065139668, 5637.915431268524, 5646.717664116788, 5650.74571644276, 5687.971560142193, 4894.363732714742, 4914.533119353656, 5064.265681476193, 5092.821751238707, 5142.382914050319, 4932.950670493285, 4941.802327906879, 5013.899722005558, 5014.047331007097, 5020.774065167058, 5005.624578156653, 5072.4166720827025, 5099.209622508517, 5140.306058682087, 5144.614153938451, 5167.534921857032, 5195.220716892459, 5203.271541984125, 5206.739663016872, 5237.0, 4893.535114164184, 4970.204767237225, 5037.168867136918, 5087.79648814047, 5091.643584949018, 5435.728213589345, 5467.507924286833, 5469.206964990094, 5520.558355331564, 5523.2543606613, 6075.2832840015335, 6085.330613632497, 6085.442880588815, 6110.541709371815, 6111.0, 6054.55060620072, 6128.625347140861, 6129.570930034899, 6136.723847426876, 6147.692615369258, 5719.370869204384, 5763.250777398953, 5771.018926782457, 5773.769049237983, 5776.335721392094, 5982.312034116046, 6258.749650013949, 6260.624362538262, 6265.4361107500545, 6265.65538895356, 5831.241938548046, 5875.588708790344, 5915.290165180119, 5987.341392446828, 5991.730372133265, 6005.099235114768, 6006.399360063959, 6022.849428764228, 6032.879342413127, 6039.456448919618, 5830.038043722823, 5898.115282707542, 5978.880422391615, 5988.790392336247, 5995.940040599626, 5946.762129514771, 6019.729112189962, 6022.638641681513, 6028.095785632103, 6057.727402266821, 4557.248054071041, 4561.178987782231, 4563.110193512233, 4567.771611419482, 4590.701604395704, 4015.578364271768, 4051.9797401012806, 4068.389741538793, 4106.445629839993, 4154.5222299435345, 5746.396628354046, 5764.971175144097, 5767.480926716616, 5775.27809023872, 5790.420957904176, 6027.9095813562835, 6099.542534309944, 6100.877982440326, 6106.175666284995, 6128.080787881764, 5493.890122197534, 5658.434156584309, 5681.971590142023, 5706.543476521868, 5737.5983681141915, 5193.714345711022, 5196.064708352457, 5214.035403450373, 5215.374155101412, 5222.21666749983, 6292.370762923671, 6307.400796924193, 6314.0, 6315.084312774622, 6316.873662526724, 5858.326292476249, 5957.970210148921, 6016.278046634432, 6020.127081573235, 6086.756529738761, 6265.060240963892, 6288.308286088618, 6288.654124023223, 6290.968545157245, 6294.213223347082, 5679.006173919465, 5960.821175364746, 5966.164736936746, 5967.398937203437, 6001.219841420588, 5981.730822112929, 6004.789832355847, 6022.668753218615, 6069.787557435468, 6073.210482637229, 5924.318703348998, 5950.702464876652, 5965.403459654173, 5966.224390829165, 5966.761329546943, 5677.261955945701, 5800.303963524404, 5869.35437101927, 5914.408559144051, 5934.228550288435, 5774.711264436677, 5803.419658034163, 5814.912776308274, 5814.970925145347, 5841.474267315962, 5918.496927761188, 5949.91075133882, 5950.702464876826, 5958.255218097738, 5964.105384192406, 5606.074997625509, 5709.20071190042, 5749.770009199585, 5780.653160810365, 5793.8551536211935], "value": 5551.898232337634, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.6378604730183, 641.8780431717973, 647.0617604473513, 658.052404537466, 663.8838203314418, 35906.461403078945, 46569.0, 47603.28595071068, 47663.999999999956, 51344.22983655239, 50728.49271507284, 51144.744276278565, 51169.48830511703, 51192.0, 51281.0, 8350.62422191003, 9128.224100951411, 9272.211861991738, 9275.72172834816, 10846.349219046842, 10740.24818262722, 11133.72165695856, 11151.609693660723, 11158.665240042812, 11160.665180044612, 11216.102711783078, 11262.943685281562, 11263.21157518974, 11267.830982535228, 11269.098472122252, 8523.403361764678, 10958.671239862817, 11025.724356891062, 11295.774084518345, 11315.15136364776, 10683.626073087482, 10964.0680542154, 10966.780664386668, 10988.230823842334, 11098.833517497244, 44110.77944610256, 44345.55654443479, 44640.0, 44644.553554464364, 44949.32576011362, 3825.7895815730126, 3871.7483363581277, 4020.3768415895292, 4187.099773548702, 4441.267190913495, 35404.0, 35881.1029724259, 35924.0, 36037.81981090104, 36285.45571816425, 4779.617630589544, 4802.927956080662, 4824.831130910401, 4858.1983972644475, 4859.441164266119, 373618.0, 375485.0, 375841.0, 376084.0, 376472.0, 41657.37513937293, 43011.35482967758, 43035.569644303156, 69299.0, 69711.65144174246, 43065.0, 43145.0, 43208.0, 43215.35176972348, 43217.783911080245, 16253.10607916576, 16438.0, 16442.0, 16452.259648315856, 16463.753043704353, 12061.155719099579, 13117.0, 13511.797323040162, 13557.661058473419, 13780.966427517977, 9682.177014953808, 9712.75718107053, 9716.805663886682, 9762.707118786448, 9795.167410770022, 3681.613430589803, 3687.539057617798, 3694.9261014779554, 3727.720420968384, 3789.886303410902, 1036.7356324137447, 1043.7025447747508, 1138.2658185470325, 1186.7092562322287, 1250.4560516175493, 33355.332893342, 33898.49152262718, 34025.31949360999, 34205.0, 34506.39830910757, 1170.2942141529086, 1176.3066756896662, 1223.7001934526097, 1258.0, 1311.731095125497, 17353.479395618153, 17665.55836104082, 17803.821961780475, 17820.833873751675, 17993.7300940486, 550012.0, 931377.0, 947076.0, 947874.0, 950310.0, 66226.66886665535, 66288.33711662918, 66827.0, 66859.33140668628, 66930.33069669339, 1416.4546649540002, 1417.9503717370048, 1433.5126057140415, 1516.6132636177704, 1517.8026856508584, 26630.733692662827, 34692.0, 36518.0, 37037.44443833291, 37723.24553508969, 60940.39059609347, 61640.691796540734, 61788.69105654443, 61957.69021154865, 62220.37779622146, 364299.0, 364506.0, 364527.0, 366723.0, 367131.0, 30595.08214753561, 30815.383692326475, 31171.0, 31270.843645781624, 31798.046058618278, 52749.472505274454, 53022.469775303296, 53385.7330713344, 54139.45860541344, 54228.72885635547, 45565.31652025288, 45844.31233531566, 46095.539044609126, 46392.30411543897, 46506.53493465022, 44211.77894110509, 44784.77607611941, 44851.327230092225, 45072.77463612792, 45683.086338272384, 45104.548954510035, 45272.32091518696, 45673.77163114163, 46181.0, 46410.7679461614, 36653.81673091617, 39572.40641390324, 40030.59969400269, 42158.578414215466, 42520.5747942529, 29603.40793184081, 29685.0, 29833.55249671213, 30192.396152077275, 30344.393112137193], "value": 21078.503028885112, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.6327353386387, 435.26874850251573, 444.1006960904169, 444.78205679217183, 449.6582597226107, 18951.33670321539, 19887.701684474745, 20376.694349584748, 20933.267335643257, 20937.47656308593, 19361.22555097798, 19675.31136410226, 19943.202271909107, 19963.0, 19985.800141998574, 5740.856478588048, 5978.372270911557, 6011.30869949957, 6020.729067191987, 6030.66831324277, 5875.618084824473, 5878.235829342179, 5960.5529585281065, 5968.7612495500125, 5973.0, 5709.800156994506, 5854.268216472941, 5856.355800861902, 5857.297124345085, 5873.148393482942, 5809.186713860042, 5913.793017244398, 5922.496587790043, 5931.851703707399, 5932.6143800653035, 5101.438841727407, 5116.513931176532, 5256.159014557691, 5257.342832145981, 5292.417834038252, 23755.287341379786, 23888.0, 23940.521189576284, 24030.519389612284, 24105.397365065837, 2298.402415371999, 2466.962995555068, 2615.4115324052127, 2779.8471084090365, 2814.451182019507, 13960.93019534906, 14211.36048877803, 14535.636609084857, 14909.776353354708, 15067.924660376628, 2046.4883779055235, 2107.1255428996947, 2107.2308607358204, 2120.862143960653, 2146.8175205107586, 77037.61481192615, 79407.20592794113, 79571.6021419895, 80383.0, 81300.59349703272, 10889.673309800719, 10996.670099897015, 11161.218714690056, 11244.775104498029, 11408.828867566994, 15523.0, 15602.141882196589, 15607.3757049719, 15618.9219053904, 15660.825438092188, 8172.0, 8329.416940814084, 8388.622511986976, 8421.989361276686, 8462.984441866893, 8034.397420193509, 8102.635381407733, 8137.511749295061, 8218.958905205554, 8284.751457456287, 6801.65991700413, 6877.89683154753, 6908.5509441886115, 6954.478414118961, 6990.300969902969, 2013.1242909334487, 2059.979400206009, 2073.7407824022, 2076.5535409886947, 2112.186808078901, 1046.947652617366, 1104.828751543512, 1121.1367247219596, 1129.7006293332274, 1145.7422080031927, 18331.17509712066, 19814.90092549528, 19935.20259189645, 20297.492562685755, 20330.085146168458, 1040.4485622620023, 1101.4052411697753, 1107.241539545414, 1109.8446217529552, 1132.1565433751744, 10927.890721092688, 11107.555697771999, 11527.942360288145, 11582.131340149523, 11624.709382265342, 471316.0, 478019.0, 503496.0, 504734.0, 509532.0, 72279.63860180666, 84229.57885210535, 87567.0, 91171.0, 93744.0, 1080.6109800471825, 1081.1350919264557, 1106.6790630717323, 1108.7616162524935, 1113.3097479562723, 17364.565885852953, 17539.0, 17594.472165835046, 17743.911280443514, 17947.0, 39893.800530997156, 41322.0, 41563.79218104011, 41737.79131104325, 42876.0, 56254.43745562492, 58215.417845822696, 58244.4175558239, 58463.70768146132, 58700.0, 7515.511491753237, 7925.920740792519, 8267.29727973129, 8538.78653033679, 8644.265237454887, 12564.560240391542, 12661.493540258287, 12880.742385152433, 12915.160514566523, 12960.0, 11208.607698730506, 11492.54029838797, 11499.827502587636, 11526.59656912004, 11558.30650160993, 11538.44231028834, 11541.422928853355, 11542.538298467967, 11543.4805433754, 11632.651020469399, 11502.769944601228, 11661.591844285409, 11789.70525736829, 11917.702057448632, 11923.344216068199, 8010.439269251096, 8014.0, 8101.230383113595, 8175.7138500152205, 8189.672413103409, 11333.659990200307, 11485.885141148481, 11557.711057223636, 11594.5362185515, 11908.345041022827], "value": 9607.463981768626, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.643089227693, 1576.9211539423027, 1596.7924169857918, 1787.9374221902235, 1923.0, 8579.656813727452, 9503.287253455992, 9768.804623907517, 9781.657641982523, 10011.449370284632, 9612.327137100407, 9707.0, 9795.804083918318, 9820.067093626112, 9822.116009559139, 2283.4177284792354, 2521.9495610087833, 2526.3305224115625, 2556.041484443334, 2597.571400718879, 2600.7399260073935, 2605.661264035682, 2621.436391175897, 2624.7768939640155, 2627.435101453187, 2591.3651155466937, 2598.519273934319, 2602.908898188564, 2603.778678812303, 2605.544029794788, 2240.764719704432, 2479.925602231936, 2547.9363015924564, 2551.413174969749, 2566.268613445159, 2482.453860150764, 2497.662815519918, 2503.0988844016138, 2514.0320976424027, 2514.798816094708, 9809.852852207223, 9860.211183105333, 9878.160356369717, 9886.90113098867, 9890.20878329732, 5901.17383566301, 6053.1828203192445, 6055.12200730896, 6056.878862422727, 6060.666663333515, 3740.4950331705145, 3777.452269420946, 3875.9224815503812, 3927.1360300734077, 3930.5872883347124, 1232.106722626098, 1265.6645988812973, 1290.6837824732906, 1320.920744755308, 1367.09771550777, 14355.85644143566, 14366.56900292993, 14390.064645798095, 14402.351894164787, 14407.207603581799, 5253.185756207794, 5260.631755777135, 5319.0957537218765, 5323.574114070942, 5386.380566234933, 11852.170348075551, 11852.407379631157, 11858.0, 11872.10959178065, 11887.049036077093, 5304.522592966651, 5902.675352855548, 5990.430909063632, 5997.040473524303, 6003.3996600339615, 5294.152935530374, 5440.809571665052, 5442.346918369742, 5489.505944465017, 5494.066008778517, 4492.573205545468, 4538.478075021349, 4549.317602359606, 4721.8347357842995, 4750.192467280571, 1380.523719316834, 1425.736238795826, 1425.964350891236, 1437.5543581489771, 1454.120257244373, 925.9120383563551, 1008.6620981970975, 1010.6563768318807, 1016.9084782369474, 1037.1339931157418, 6210.813675589739, 6337.6197428154455, 6339.841503962438, 6348.746050158034, 6453.290138084807, 433.1877729257642, 452.1409322287614, 456.27680126998416, 456.77161419290354, 466.37272867992493, 4168.645665118438, 4427.977860110679, 4537.886552836206, 4622.607078398374, 4741.051789642085, 12423.068269879795, 12441.813372799415, 12638.684032899251, 12672.619821405373, 12693.365331733561, 12423.503059877685, 12507.93746031264, 12539.435725392379, 12563.685907852378, 12565.623031309075, 9067.501287429122, 9146.359754817233, 9160.770980725536, 9238.353315267866, 9275.860862087075, 6433.742650294123, 6493.675316234264, 6517.27292267559, 6572.047053177167, 6572.277049524453, 11065.19742883374, 11382.772344553228, 11428.82856757166, 11640.126990475917, 11663.0, 11260.83108753353, 11633.53465861356, 11698.883011169779, 11782.234154779911, 11931.642050738492, 8051.275385215359, 8606.569671516274, 8880.644774209217, 8898.0, 9077.228435582785, 9685.70942871715, 9735.756606084904, 9764.121229089416, 9803.803923921625, 9809.656662016796, 8512.796038895196, 8739.257163141205, 8795.120487951153, 8832.0, 8833.779155521164, 8170.223828736261, 8193.836123277382, 8418.873716894374, 8448.83102337962, 8474.449160804528, 7346.485745997729, 7413.406927445899, 7415.2214017527385, 7424.851502969802, 7442.590657513889, 5382.811601593926, 5455.781768729206, 5557.555395568425, 5658.066419040977, 5744.885102298014, 5810.854728631818, 5827.24245848037, 5851.297844258716, 5868.501177399969, 5986.311574169026], "value": 5058.959805162862, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.17619475207016, 359.2653024564765, 363.35867194401885, 369.23752451188295, 370.73492452896176, 5513.4210907854595, 5532.6957017364075, 5533.47431993961, 5580.6930618815995, 5588.273524441827, 5193.974030129853, 5435.945640543603, 5457.863553411166, 5462.235287059815, 5473.397926228122, 4231.492220933493, 4279.978600106995, 4281.736887618153, 4291.506476755182, 4293.65650747941, 4228.513720922103, 4259.91480170398, 4288.399624052635, 4295.398644189816, 4299.247631664446, 4240.512341080785, 4248.2140803951215, 4255.5318914919335, 4289.828406863721, 4306.97846510767, 4132.0, 4209.97895010526, 4234.724742891703, 4236.428082208924, 4260.360945858115, 3652.9634703652887, 3710.3877860153043, 3722.8510859565586, 3737.495438115847, 3759.586445490994, 5377.489138531833, 5378.784848606051, 5388.218708287315, 5402.540784033323, 5431.6740995540395, 4780.545848144421, 4871.610271178297, 4874.975625121887, 4885.5847252983185, 4929.866130789938, 5709.745445096297, 5738.7991420300505, 5740.54075673945, 5750.511206547407, 5759.510441612468, 4123.752574845519, 4132.504099508048, 4142.855000075013, 4157.792110394468, 4173.269677806372, 5749.770009199627, 5839.766409343663, 5860.824175274749, 5988.490978266853, 6087.54343424245, 4281.892952676146, 4282.164977829293, 4293.218314399552, 4313.870583882489, 4324.762138082373, 4321.546237645065, 4372.40972468719, 4376.352306854371, 4378.146261478981, 4395.340698895127, 4694.507076756891, 4788.63523895688, 4898.657094003385, 4918.851934761756, 4980.77586508608, 5784.814113106803, 5800.56495762811, 5804.564657650693, 5813.302403711497, 5818.0, 1494.880409567232, 1508.7133444645483, 1515.3559737111684, 1524.8170219573722, 1530.143119852886, 949.3212353167519, 976.2970661123979, 976.8437050071955, 982.2731178927543, 987.8073775613686, 4992.600591952635, 4995.700257984533, 5014.323066385991, 5032.597392208614, 5101.7194054327365, 5256.6057545684225, 5267.920981185285, 5280.683159010472, 5293.894122117536, 5331.226972089103, 4845.207273308898, 4958.57852082577, 5006.873453473012, 5010.574101201366, 5035.899282014412, 2794.3433293176085, 2821.5767634854524, 2826.5618829081113, 2848.7293707097792, 2898.318895059659, 5272.340957380327, 5392.541633961078, 5452.345718513724, 5458.972705136449, 5471.37079235893, 5183.896322073613, 5397.136458166753, 5409.891802163935, 5425.430329815313, 5438.401775804658, 5422.593305502103, 5426.430224826494, 5483.945160548423, 5485.4788795064405, 5537.8061767837935, 4879.975600121977, 5301.840944771663, 5323.467653234646, 5347.518723314764, 5379.300690910157, 5220.504052115043, 5266.578673706018, 5319.4732502061215, 5331.573474122139, 5334.866628334323, 6015.729292181775, 6047.455728984412, 6143.389059649542, 6204.348543402878, 6211.353991192247, 4198.202341555095, 4212.789360532022, 4213.683973701924, 4218.489998450917, 4227.682923780667, 5854.970725146347, 5995.700214989321, 5997.190379298913, 6061.030235162353, 6110.602810817282, 5867.647941123546, 5885.882282354414, 5885.970570147122, 5902.498287645598, 5906.852328691817, 5788.189653448605, 5819.5053420459735, 5823.903394777792, 5834.124881267844, 5839.708014599338, 5630.690312032878, 5666.688332141772, 5806.332271788799, 5807.315878395218, 5812.21535092774, 5905.970470147622, 5910.881782364415, 5916.3492015877355, 5934.910976335272, 5936.13925980739, 5471.2887324647545, 5674.233978412862, 5799.0, 5809.419058094157, 5813.215215945962], "value": 4304.030528575351, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.8806179073142, 817.2236375443331, 879.6261588824748, 881.03526638331, 915.7069737683939, 14731.705365892676, 14829.703405931876, 14842.554723358304, 14852.33164507597, 14876.586724261191, 13209.669758256048, 13591.184528928246, 13769.0, 13776.862231377683, 13840.930795346034, 860.8450478913793, 877.4296707140346, 878.1569693094636, 880.2561835249236, 880.828238493494, 730.0399974034154, 850.8893843800329, 873.5108339329964, 873.9257163141141, 875.6366108065146, 912.2428384440898, 914.4878867834034, 916.3997581584073, 917.0829170829171, 918.0085507651753, 758.4614923404389, 910.5037754423855, 911.9954400227956, 912.3795818843184, 913.5888850017519, 654.1398061549095, 674.4671709349608, 677.5189615373091, 678.9762358317436, 679.239252037716, 13627.454901803916, 13686.247256400893, 13698.65753356174, 13705.040647154703, 13706.0, 14248.786268205986, 14371.353289102015, 14434.855651443457, 14655.193964331958, 14695.559133225914, 12924.224546527144, 13028.0, 13508.797368039488, 13549.322533873266, 13895.374708138057, 4576.725396476222, 4603.769811509411, 4612.331211974245, 4642.117997580449, 4662.067586482717, 70515.2948470519, 70581.64709176472, 70609.29390706078, 70975.0, 74182.99999999946, 11033.0, 11233.438328083563, 11361.772764544829, 11373.147013973985, 11846.526138954345, 20641.793582063987, 21166.153353865982, 21366.572668546854, 21368.358949231548, 21694.0, 8166.959165204136, 8186.713465028695, 8371.246587807005, 8858.923017300473, 8891.911080889238, 4697.6241900647865, 4766.594839438687, 4788.569028787356, 4862.027594481118, 4935.8025678972435, 3605.837737301828, 3728.440733889884, 3731.7014638828828, 3735.0662334416397, 3738.1962877981364, 595.1994567306965, 661.3089321658878, 663.0915645565606, 664.5248647217167, 666.7732970789955, 727.4943913979839, 729.0886392009987, 765.4374035084209, 765.9425543084291, 769.415244414238, 10135.189184865194, 10185.388876667424, 10382.532786024496, 10772.94613526943, 10825.67522974312, 714.731975509184, 718.2027948976631, 724.5037149552574, 725.3073314984177, 731.6305265840716, 4559.54404559548, 4757.333973243749, 4826.8793280168275, 4878.82924097662, 4879.219324907997, 111505.0, 113267.0, 116818.41590791992, 117511.8248817518, 118154.4092279533, 100556.49721751345, 100622.0, 100751.0, 101390.0, 102086.0, 86154.56922715346, 87151.0, 89097.10902890888, 89123.0, 90060.99999999869, 9267.72196834096, 9300.255979521757, 9391.577379017825, 9442.575084121368, 9494.810103798023, 54634.726826365615, 55055.72472137614, 55847.72076139593, 56320.43679563152, 56923.0, 58620.0, 79675.0, 80476.59761701155, 80502.19497804948, 80668.0, 10766.138708903425, 10974.122070234522, 11570.826437603611, 11679.06567474617, 12025.518979240733, 11870.10860884866, 12151.392430378623, 12683.936580317408, 13426.798598020841, 13676.589702308947, 11319.490622922152, 11440.599579014695, 11509.366984815915, 14383.352749126523, 15008.174550399834, 12571.685707857378, 12604.936975315064, 12637.494500219887, 12733.745325093261, 13843.100198487065, 12598.748025039264, 13105.344732763133, 13153.0, 13547.729045418839, 13954.23251721165, 11074.833877491683, 11091.667249982513, 11541.36522491271, 12128.211666241667, 12338.75322493563, 11149.129176323197, 11268.830967535658, 11280.153988450735, 11405.942970285096, 11496.655100347003], "value": 7658.748812562771, "unit": "score"}], "name": "dromaeo_css", "value": 7565.504821569301, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 109.0, 109.0, 108.0, 108.0, 108.0, 110.0, 109.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 124.0, 167.0, 123.0, 125.0, 155.0, 123.0, 160.0, 124.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 161.0, 159.0, 161.0, 161.0, 159.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 122.0, 76.0, 76.0, 84.0, 76.0, 97.0, 75.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 116.0, 85.0, 84.0, 85.0, 123.0, 84.0, 84.0, 137.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 48.0, 51.0, 51.0, 49.0, 50.0, 50.0, 53.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 83.0, 77.0, 77.0, 77.0, 77.0, 117.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 117.0, 119.0, 124.0, 118.0, 126.0, 125.0, 133.0, 118.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 155.0, 152.0, 153.0, 154.0, 155.0, 156.0, 156.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 60.8, "unit": "score"}], "name": "kraken", "value": 1472.4, "alertThreshold": 2.0}]} 04:27:32 INFO - Return code: 0 04:27:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:27:32 ERROR - # TBPL SUCCESS # 04:27:32 INFO - Running post-action listener: _resource_record_post_action 04:27:32 INFO - ##### 04:27:32 INFO - ##### Finished run-tests step (success) 04:27:32 INFO - ##### 04:27:32 INFO - Running post-run listener: _resource_record_post_run 04:27:32 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 24576; Write bytes: 507764736; Read time: 292; Write time: 384592 04:27:32 INFO - TinderboxPrint: CPU usage
13.1% 04:27:32 INFO - TinderboxPrint: I/O read bytes / time
24,576 / 292 04:27:32 INFO - TinderboxPrint: I/O write bytes / time
507,764,736 / 384,592 04:27:32 INFO - TinderboxPrint: CPU idle
8,243.3 (86.9%) 04:27:32 INFO - TinderboxPrint: CPU user
1,202.9 (12.7%) 04:27:32 INFO - TinderboxPrint: Swap in / out
0 / 0 04:27:32 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 16384; Write bytes: 252256256; Read time: 236; Write time: 249256 04:27:32 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 255021056; Read time: 56; Write time: 135336 04:27:32 INFO - Running post-run listener: _upload_blobber_files 04:27:32 INFO - Blob upload gear active. 04:27:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:27:32 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 04:27:32 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'] 04:27:32 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 04:27:33 INFO - (blobuploader) - INFO - Open directory for files ... 04:27:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:27:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:27:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:36 INFO - (blobuploader) - INFO - Done attempting. 04:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:37 INFO - (blobuploader) - INFO - Done attempting. 04:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:27:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:38 INFO - (blobuploader) - INFO - Done attempting. 04:27:38 INFO - (blobuploader) - INFO - Iteration through files over. 04:27:38 INFO - Return code: 0 04:27:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:27:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:27:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afec38695257dbda2bca5998763870fed744ca78119978ba259bdce087ffe1c68f0e41b57bef8e32dd8906a4f91d54701a1aec092d73b656f47c92e7a94d284c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed0cde762dcda5f27fbea3c34effc8e3d3c1f380291c44cf63ed794c730914d693764504ed7f5191d6a0db92b82ae92c5e1f24e87f2c2b0313282cee097c05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34da36ccce8e6955b47776e19dbf51c5de70409753428e0919a93d9cebdcbbd28a9e78c47c3c5015b511b660d1a65facb57fabf5916a8cbd9536d6c1700dd220"} 04:27:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:27:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:27:38 INFO - Contents: 04:27:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afec38695257dbda2bca5998763870fed744ca78119978ba259bdce087ffe1c68f0e41b57bef8e32dd8906a4f91d54701a1aec092d73b656f47c92e7a94d284c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed0cde762dcda5f27fbea3c34effc8e3d3c1f380291c44cf63ed794c730914d693764504ed7f5191d6a0db92b82ae92c5e1f24e87f2c2b0313282cee097c05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34da36ccce8e6955b47776e19dbf51c5de70409753428e0919a93d9cebdcbbd28a9e78c47c3c5015b511b660d1a65facb57fabf5916a8cbd9536d6c1700dd220"} 04:27:38 INFO - Running post-run listener: copy_logs_to_upload_dir 04:27:38 INFO - Copying logs to upload dir... 04:27:38 INFO - mkdir: /builds/slave/test/build/upload/logs 04:27:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.556497 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-03 04:27:37.491076) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 04:27:37.494957) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 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/afec38695257dbda2bca5998763870fed744ca78119978ba259bdce087ffe1c68f0e41b57bef8e32dd8906a4f91d54701a1aec092d73b656f47c92e7a94d284c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed0cde762dcda5f27fbea3c34effc8e3d3c1f380291c44cf63ed794c730914d693764504ed7f5191d6a0db92b82ae92c5e1f24e87f2c2b0313282cee097c05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34da36ccce8e6955b47776e19dbf51c5de70409753428e0919a93d9cebdcbbd28a9e78c47c3c5015b511b660d1a65facb57fabf5916a8cbd9536d6c1700dd220"} build_url:https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/FUEeBfmtQXeihgHe0tvNCQ/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/afec38695257dbda2bca5998763870fed744ca78119978ba259bdce087ffe1c68f0e41b57bef8e32dd8906a4f91d54701a1aec092d73b656f47c92e7a94d284c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed0cde762dcda5f27fbea3c34effc8e3d3c1f380291c44cf63ed794c730914d693764504ed7f5191d6a0db92b82ae92c5e1f24e87f2c2b0313282cee097c05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34da36ccce8e6955b47776e19dbf51c5de70409753428e0919a93d9cebdcbbd28a9e78c47c3c5015b511b660d1a65facb57fabf5916a8cbd9536d6c1700dd220"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 04:27:37.520699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:27:37.521092) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467543721.665156-125157488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 04:27:37.602383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 04:27:37.602729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 04:27:37.603028) ========= ========= Total master_lag: 0.17 =========