builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-062 starttime: 1483052915.2 results: success (0) buildid: 20161229133006 builduid: c47a928050654e77aeae435ed5b64336 revision: 79ef936724454728beeeba41fb76a61d02c7c226 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.199442) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.199902) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.200198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.218179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.218460) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 15:08:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 24.0M=0s 2016-12-29 15:08:36 (24.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125067 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.356712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.356996) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014856 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:35.407414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 15:08:35.407731) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 79ef936724454728beeeba41fb76a61d02c7c226 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 79ef936724454728beeeba41fb76a61d02c7c226 --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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 15:08:36,528 truncating revision to first 12 chars 2016-12-29 15:08:36,528 Setting DEBUG logging. 2016-12-29 15:08:36,528 attempt 1/10 2016-12-29 15:08:36,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/79ef93672445?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 15:08:37,307 unpacking tar archive at: mozilla-central-79ef93672445/testing/mozharness/ program finished with exit code 0 elapsedTime=1.071564 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 15:08:36.492382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:36.492692) ========= script_repo_revision: 79ef936724454728beeeba41fb76a61d02c7c226 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:36.493075) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:36.493359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 15:08:36.506118) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-29 15:08:36.506407) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:08:37 INFO - MultiFileLogger online at 20161229 15:08:37 in /builds/slave/test 15:08:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:08:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:08:37 INFO - {'append_to_log': False, 15:08:37 INFO - 'base_work_dir': '/builds/slave/test', 15:08:37 INFO - 'blob_upload_branch': 'Firefox', 15:08:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:08:37 INFO - 'branch': 'Firefox', 15:08:37 INFO - 'buildbot_json_path': 'buildprops.json', 15:08:37 INFO - 'config_files': ('talos/linux_config.py',), 15:08:37 INFO - 'default_actions': ('clobber', 15:08:37 INFO - 'read-buildbot-config', 15:08:37 INFO - 'download-and-extract', 15:08:37 INFO - 'populate-webroot', 15:08:37 INFO - 'create-virtualenv', 15:08:37 INFO - 'install', 15:08:37 INFO - 'run-tests'), 15:08:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:08:37 INFO - 'download_minidump_stackwalk': True, 15:08:37 INFO - 'download_symbols': 'ondemand', 15:08:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:08:37 INFO - 'tooltool.py': '/tools/tooltool.py', 15:08:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:08:37 INFO - '/tools/misc-python/virtualenv.py')}, 15:08:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:08:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:08:37 INFO - 'installer_path': 'installer.exe', 15:08:37 INFO - 'log_level': 'info', 15:08:37 INFO - 'log_name': 'talos', 15:08:37 INFO - 'log_to_console': True, 15:08:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:08:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:08:37 INFO - 'opt_config_files': (), 15:08:37 INFO - 'pip_index': False, 15:08:37 INFO - 'sps_profile': False, 15:08:37 INFO - 'sps_profile_interval': 0, 15:08:37 INFO - 'suite': 'dromaeojs-e10s', 15:08:37 INFO - 'system_bits': '32', 15:08:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:08:37 INFO - 'title': 'talos-linux64-ix-062', 15:08:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:08:37 INFO - 'use_talos_json': True, 15:08:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:08:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:08:37 INFO - 'work_dir': 'build'} 15:08:37 INFO - [mozharness: 2016-12-29 23:08:37.723002Z] Running clobber step. 15:08:37 INFO - Running pre-action listener: _resource_record_pre_action 15:08:37 INFO - Running main action method: clobber 15:08:37 INFO - rmtree: /builds/slave/test/build 15:08:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:08:38 INFO - Running post-action listener: _resource_record_post_action 15:08:38 INFO - [mozharness: 2016-12-29 23:08:38.184925Z] Finished clobber step (success) 15:08:38 INFO - [mozharness: 2016-12-29 23:08:38.185026Z] Running read-buildbot-config step. 15:08:38 INFO - Running pre-action listener: _resource_record_pre_action 15:08:38 INFO - Running main action method: read_buildbot_config 15:08:38 INFO - Using buildbot properties: 15:08:38 INFO - { 15:08:38 INFO - "project": "", 15:08:38 INFO - "product": "firefox", 15:08:38 INFO - "script_repo_revision": "production", 15:08:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:08:38 INFO - "repository": "", 15:08:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 15:08:38 INFO - "buildid": "20161229133006", 15:08:38 INFO - "pgo_build": "True", 15:08:38 INFO - "basedir": "/builds/slave/test", 15:08:38 INFO - "buildnumber": 228, 15:08:38 INFO - "slavename": "talos-linux64-ix-062", 15:08:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:08:38 INFO - "platform": "ubuntu64_hw", 15:08:38 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 15:08:38 INFO - "branch": "mozilla-central", 15:08:38 INFO - "repo_path": "mozilla-central", 15:08:38 INFO - "revision": "79ef936724454728beeeba41fb76a61d02c7c226", 15:08:38 INFO - "stage_platform": "linux64-pgo", 15:08:38 INFO - "builduid": "c47a928050654e77aeae435ed5b64336", 15:08:38 INFO - "slavebuilddir": "test" 15:08:38 INFO - } 15:08:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 15:08:38 INFO - Running post-action listener: _resource_record_post_action 15:08:38 INFO - [mozharness: 2016-12-29 23:08:38.187650Z] Finished read-buildbot-config step (success) 15:08:38 INFO - [mozharness: 2016-12-29 23:08:38.187740Z] Running download-and-extract step. 15:08:38 INFO - Running pre-action listener: _resource_record_pre_action 15:08:38 INFO - Running main action method: download_and_extract 15:08:38 INFO - mkdir: /builds/slave/test/build/tests 15:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:38 INFO - trying https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:08:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:08:38 INFO - Downloaded 1361 bytes. 15:08:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:08:38 INFO - Using the following test package requirements: 15:08:38 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:08:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:08:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:08:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'jsshell-linux-x86_64.zip'], 15:08:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:08:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:08:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:08:38 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:08:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:08:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:08:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:08:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:08:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:08:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:08:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:08:40 INFO - Expected file size: 21835785 15:08:40 INFO - Obtained file size: 21835785 15:08:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:08:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:08:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:08:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:08:45 INFO - Expected file size: 21835785 15:08:45 INFO - Obtained file size: 21835785 15:08:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 15:08:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:08:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:08:51 INFO - Expected file size: 14409776 15:08:51 INFO - Obtained file size: 14409776 15:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:51 INFO - trying https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:08:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:08:54 INFO - Downloaded 61379612 bytes. 15:08:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:08:54 INFO - mkdir: /builds/slave/test/properties 15:08:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:08:54 INFO - Writing to file /builds/slave/test/properties/build_url 15:08:54 INFO - Contents: 15:08:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:08:54 INFO - Running post-action listener: _resource_record_post_action 15:08:54 INFO - Running post-action listener: set_extra_try_arguments 15:08:54 INFO - [mozharness: 2016-12-29 23:08:54.781220Z] Finished download-and-extract step (success) 15:08:54 INFO - [mozharness: 2016-12-29 23:08:54.781448Z] Running populate-webroot step. 15:08:54 INFO - Running pre-action listener: _resource_record_pre_action 15:08:54 INFO - Running main action method: populate_webroot 15:08:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:08:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:08:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:08:54 INFO - '--tppagecycles', 15:08:54 INFO - '7'], 15:08:54 INFO - 'tests': ['tsvgm']}}, 15:08:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:08:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:08:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:08:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:08:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:08:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:08:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['damp', 'tps']}, 15:08:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'tests': ['damp', 'tps']}, 15:08:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['dromaeo_dom']}, 15:08:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:08:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['basic_compositor_video']}, 15:08:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:08:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'other-e10s': {'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['a11yr', 15:08:54 INFO - 'ts_paint', 15:08:54 INFO - 'tpaint', 15:08:54 INFO - 'sessionrestore', 15:08:54 INFO - 'sessionrestore_no_auto_restore', 15:08:54 INFO - 'tabpaint']}, 15:08:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['tsvgx', 15:08:54 INFO - 'tsvgr_opacity', 15:08:54 INFO - 'tart', 15:08:54 INFO - 'tscrollx', 15:08:54 INFO - 'cart', 15:08:54 INFO - 'tsvg_static']}, 15:08:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:08:54 INFO - 'tsvgr_opacity', 15:08:54 INFO - 'tart', 15:08:54 INFO - 'tscrollx', 15:08:54 INFO - 'cart', 15:08:54 INFO - 'tsvg_static']}, 15:08:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'talos_options': ['--disable-e10s'], 15:08:54 INFO - 'tests': ['tp5o']}, 15:08:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:08:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'talos_options': ['--disable-e10s', 15:08:54 INFO - '--xperf_path', 15:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:08:54 INFO - 'tests': ['tp5n']}, 15:08:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:08:54 INFO - 'talos_options': ['--xperf_path', 15:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:08:54 INFO - 'tests': ['tp5n']}}, 15:08:54 INFO - 'talos.zip': {'path': '', 15:08:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:08:54 INFO - Running post-action listener: _resource_record_post_action 15:08:54 INFO - [mozharness: 2016-12-29 23:08:54.802685Z] Finished populate-webroot step (success) 15:08:54 INFO - [mozharness: 2016-12-29 23:08:54.802775Z] Running create-virtualenv step. 15:08:54 INFO - Running pre-action listener: _resource_record_pre_action 15:08:54 INFO - Running main action method: create_virtualenv 15:08:54 INFO - Creating virtualenv /builds/slave/test/build/venv 15:08:54 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 15:08:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:08:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:08:54 INFO - Using real prefix '/usr' 15:08:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:08:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:08:57 INFO - Installing pip.................done. 15:08:57 INFO - Return code: 0 15:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:08:57 INFO - Reading from file tmpfile_stdout 15:08:57 INFO - Output received: 15:08:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:08:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:57 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:08:57 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 15:08:57 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 15:08:57 INFO - Using env: {'DISPLAY': ':0', 15:08:57 INFO - 'HOME': '/home/cltbld', 15:08:57 INFO - 'LANG': 'en_US.UTF-8', 15:08:57 INFO - 'LANGUAGE': 'en_US:en', 15:08:57 INFO - 'LOGNAME': 'cltbld', 15:08:57 INFO - 'MAIL': '/var/mail/cltbld', 15:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:57 INFO - 'MOZ_NO_REMOTE': '1', 15:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:57 INFO - 'NO_EM_RESTART': '1', 15:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:57 INFO - 'PWD': '/builds/slave/test', 15:08:57 INFO - 'SHELL': '/bin/bash', 15:08:57 INFO - 'SHLVL': '1', 15:08:57 INFO - 'TERM': 'linux', 15:08:57 INFO - 'TMOUT': '86400', 15:08:57 INFO - 'USER': 'cltbld', 15:08:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:57 INFO - '_': '/tools/buildbot/bin/python'} 15:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:57 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 15:08:57 INFO - Cleaning up... 15:08:57 INFO - Return code: 0 15:08:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:57 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:08:57 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 15:08:57 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 15:08:57 INFO - Using env: {'DISPLAY': ':0', 15:08:57 INFO - 'HOME': '/home/cltbld', 15:08:57 INFO - 'LANG': 'en_US.UTF-8', 15:08:57 INFO - 'LANGUAGE': 'en_US:en', 15:08:57 INFO - 'LOGNAME': 'cltbld', 15:08:57 INFO - 'MAIL': '/var/mail/cltbld', 15:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:57 INFO - 'MOZ_NO_REMOTE': '1', 15:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:57 INFO - 'NO_EM_RESTART': '1', 15:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:57 INFO - 'PWD': '/builds/slave/test', 15:08:57 INFO - 'SHELL': '/bin/bash', 15:08:57 INFO - 'SHLVL': '1', 15:08:57 INFO - 'TERM': 'linux', 15:08:57 INFO - 'TMOUT': '86400', 15:08:57 INFO - 'USER': 'cltbld', 15:08:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:57 INFO - '_': '/tools/buildbot/bin/python'} 15:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:58 INFO - Downloading/unpacking psutil>=3.1.1 15:08:58 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 15:08:58 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 15:08:58 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 15:08:58 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 15:08:58 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 15:08:58 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 15:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:00 INFO - Installing collected packages: psutil 15:09:00 INFO - Running setup.py install for psutil 15:09:00 INFO - building 'psutil._psutil_linux' extension 15:09:00 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 15:09:00 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 15:09:00 INFO - building 'psutil._psutil_posix' extension 15:09:00 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 15:09:00 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 15:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:00 INFO - Successfully installed psutil 15:09:00 INFO - Cleaning up... 15:09:00 INFO - Return code: 0 15:09:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:00 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:00 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 15:09:00 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 15:09:00 INFO - Using env: {'DISPLAY': ':0', 15:09:00 INFO - 'HOME': '/home/cltbld', 15:09:00 INFO - 'LANG': 'en_US.UTF-8', 15:09:00 INFO - 'LANGUAGE': 'en_US:en', 15:09:00 INFO - 'LOGNAME': 'cltbld', 15:09:00 INFO - 'MAIL': '/var/mail/cltbld', 15:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:00 INFO - 'MOZ_NO_REMOTE': '1', 15:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:00 INFO - 'NO_EM_RESTART': '1', 15:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:00 INFO - 'PWD': '/builds/slave/test', 15:09:00 INFO - 'SHELL': '/bin/bash', 15:09:00 INFO - 'SHLVL': '1', 15:09:00 INFO - 'TERM': 'linux', 15:09:00 INFO - 'TMOUT': '86400', 15:09:00 INFO - 'USER': 'cltbld', 15:09:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:00 INFO - '_': '/tools/buildbot/bin/python'} 15:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:09:00 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 15:09:00 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 15:09:00 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 15:09:00 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 15:09:00 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 15:09:00 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 15:09:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:09:03 INFO - Installing collected packages: mozsystemmonitor 15:09:03 INFO - Running setup.py install for mozsystemmonitor 15:09:03 INFO - Successfully installed mozsystemmonitor 15:09:03 INFO - Cleaning up... 15:09:03 INFO - Return code: 0 15:09:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:03 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:03 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 15:09:03 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 15:09:03 INFO - Using env: {'DISPLAY': ':0', 15:09:03 INFO - 'HOME': '/home/cltbld', 15:09:03 INFO - 'LANG': 'en_US.UTF-8', 15:09:03 INFO - 'LANGUAGE': 'en_US:en', 15:09:03 INFO - 'LOGNAME': 'cltbld', 15:09:03 INFO - 'MAIL': '/var/mail/cltbld', 15:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:03 INFO - 'MOZ_NO_REMOTE': '1', 15:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:03 INFO - 'NO_EM_RESTART': '1', 15:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:03 INFO - 'PWD': '/builds/slave/test', 15:09:03 INFO - 'SHELL': '/bin/bash', 15:09:03 INFO - 'SHLVL': '1', 15:09:03 INFO - 'TERM': 'linux', 15:09:03 INFO - 'TMOUT': '86400', 15:09:03 INFO - 'USER': 'cltbld', 15:09:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:03 INFO - '_': '/tools/buildbot/bin/python'} 15:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:09:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:09:05 INFO - warning: no files found matching '*.txt' 15:09:05 INFO - no previously-included directories found matching 'build' 15:09:05 INFO - no previously-included directories found matching 'dist' 15:09:05 INFO - no previously-included directories found matching '.git*' 15:09:05 INFO - Installing collected packages: jsonschema, functools32 15:09:05 INFO - Running setup.py install for functools32 15:09:05 INFO - warning: no files found matching '*.txt' 15:09:05 INFO - no previously-included directories found matching 'build' 15:09:05 INFO - no previously-included directories found matching 'dist' 15:09:05 INFO - no previously-included directories found matching '.git*' 15:09:05 INFO - Successfully installed jsonschema functools32 15:09:05 INFO - Cleaning up... 15:09:05 INFO - Return code: 0 15:09:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:09:05 INFO - Using env: {'DISPLAY': ':0', 15:09:05 INFO - 'HOME': '/home/cltbld', 15:09:05 INFO - 'LANG': 'en_US.UTF-8', 15:09:05 INFO - 'LANGUAGE': 'en_US:en', 15:09:05 INFO - 'LOGNAME': 'cltbld', 15:09:05 INFO - 'MAIL': '/var/mail/cltbld', 15:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:05 INFO - 'MOZ_NO_REMOTE': '1', 15:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:05 INFO - 'NO_EM_RESTART': '1', 15:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:05 INFO - 'PWD': '/builds/slave/test', 15:09:05 INFO - 'SHELL': '/bin/bash', 15:09:05 INFO - 'SHLVL': '1', 15:09:05 INFO - 'TERM': 'linux', 15:09:05 INFO - 'TMOUT': '86400', 15:09:05 INFO - 'USER': 'cltbld', 15:09:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:05 INFO - '_': '/tools/buildbot/bin/python'} 15:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:09:06 INFO - Cleaning up... 15:09:06 INFO - Return code: 0 15:09:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:09:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:06 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:06 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 15:09:06 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 15:09:06 INFO - Using env: {'DISPLAY': ':0', 15:09:06 INFO - 'HOME': '/home/cltbld', 15:09:06 INFO - 'LANG': 'en_US.UTF-8', 15:09:06 INFO - 'LANGUAGE': 'en_US:en', 15:09:06 INFO - 'LOGNAME': 'cltbld', 15:09:06 INFO - 'MAIL': '/var/mail/cltbld', 15:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:06 INFO - 'MOZ_NO_REMOTE': '1', 15:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:06 INFO - 'NO_EM_RESTART': '1', 15:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:06 INFO - 'PWD': '/builds/slave/test', 15:09:06 INFO - 'SHELL': '/bin/bash', 15:09:06 INFO - 'SHLVL': '1', 15:09:06 INFO - 'TERM': 'linux', 15:09:06 INFO - 'TMOUT': '86400', 15:09:06 INFO - 'USER': 'cltbld', 15:09:06 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:06 INFO - '_': '/tools/buildbot/bin/python'} 15:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:09:06 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 15:09:06 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 15:09:06 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 15:09:06 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 15:09:06 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 15:09:06 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 15:09:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:09:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:09:08 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 15:09:08 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 15:09:08 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 15:09:08 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 15:09:08 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 15:09:08 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 15:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:09:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:09 INFO - Downloading docopt-0.6.1.tar.gz 15:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:09:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:09:09 INFO - Running setup.py install for blobuploader 15:09:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:09:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:09:09 INFO - Running setup.py install for requests 15:09:09 INFO - Running setup.py install for docopt 15:09:09 INFO - Successfully installed blobuploader requests docopt 15:09:09 INFO - Cleaning up... 15:09:09 INFO - Return code: 0 15:09:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:09 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 15:09:09 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 15:09:09 INFO - Using env: {'DISPLAY': ':0', 15:09:09 INFO - 'HOME': '/home/cltbld', 15:09:09 INFO - 'LANG': 'en_US.UTF-8', 15:09:09 INFO - 'LANGUAGE': 'en_US:en', 15:09:09 INFO - 'LOGNAME': 'cltbld', 15:09:09 INFO - 'MAIL': '/var/mail/cltbld', 15:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:09 INFO - 'MOZ_NO_REMOTE': '1', 15:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:09 INFO - 'NO_EM_RESTART': '1', 15:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:09 INFO - 'PWD': '/builds/slave/test', 15:09:09 INFO - 'SHELL': '/bin/bash', 15:09:09 INFO - 'SHLVL': '1', 15:09:09 INFO - 'TERM': 'linux', 15:09:09 INFO - 'TMOUT': '86400', 15:09:09 INFO - 'USER': 'cltbld', 15:09:09 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:09 INFO - '_': '/tools/buildbot/bin/python'} 15:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:10 INFO - Running setup.py (path:/tmp/pip-LgRWhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:10 INFO - Running setup.py (path:/tmp/pip-T9puS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:10 INFO - Running setup.py (path:/tmp/pip-dFTJvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:10 INFO - Running setup.py (path:/tmp/pip-heIKl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:10 INFO - Running setup.py (path:/tmp/pip-DIBbM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:10 INFO - Running setup.py (path:/tmp/pip-XrVVTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:10 INFO - Running setup.py (path:/tmp/pip-1A7ov4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:10 INFO - Running setup.py (path:/tmp/pip-REdi_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:10 INFO - Running setup.py (path:/tmp/pip-vPwBC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:10 INFO - Running setup.py (path:/tmp/pip-ubx04V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:10 INFO - Running setup.py (path:/tmp/pip-8nIoXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:10 INFO - Running setup.py (path:/tmp/pip-oHaDDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:11 INFO - Running setup.py (path:/tmp/pip-l_9gKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:11 INFO - Running setup.py (path:/tmp/pip-gf0H0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:11 INFO - Running setup.py (path:/tmp/pip-rxgiQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:11 INFO - Running setup.py (path:/tmp/pip-idkhGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:11 INFO - Running setup.py (path:/tmp/pip-LYJVsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:09:11 INFO - Running setup.py install for manifestparser 15:09:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:09:11 INFO - Running setup.py install for mozcrash 15:09:11 INFO - Running setup.py install for mozdebug 15:09:11 INFO - Running setup.py install for mozdevice 15:09:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:09:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:09:11 INFO - Running setup.py install for mozfile 15:09:11 INFO - Running setup.py install for mozhttpd 15:09:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozinfo 15:09:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozInstall 15:09:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozleak 15:09:12 INFO - Running setup.py install for mozlog 15:09:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for moznetwork 15:09:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozprocess 15:09:12 INFO - Running setup.py install for mozprofile 15:09:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozrunner 15:09:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:09:12 INFO - Running setup.py install for mozscreenshot 15:09:12 INFO - Running setup.py install for moztest 15:09:13 INFO - Running setup.py install for mozversion 15:09:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:09:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:09:13 INFO - Cleaning up... 15:09:13 INFO - Return code: 0 15:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:13 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:13 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 15:09:13 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 15:09:13 INFO - Using env: {'DISPLAY': ':0', 15:09:13 INFO - 'HOME': '/home/cltbld', 15:09:13 INFO - 'LANG': 'en_US.UTF-8', 15:09:13 INFO - 'LANGUAGE': 'en_US:en', 15:09:13 INFO - 'LOGNAME': 'cltbld', 15:09:13 INFO - 'MAIL': '/var/mail/cltbld', 15:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:13 INFO - 'MOZ_NO_REMOTE': '1', 15:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:13 INFO - 'NO_EM_RESTART': '1', 15:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:13 INFO - 'PWD': '/builds/slave/test', 15:09:13 INFO - 'SHELL': '/bin/bash', 15:09:13 INFO - 'SHLVL': '1', 15:09:13 INFO - 'TERM': 'linux', 15:09:13 INFO - 'TMOUT': '86400', 15:09:13 INFO - 'USER': 'cltbld', 15:09:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:13 INFO - '_': '/tools/buildbot/bin/python'} 15:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:13 INFO - Running setup.py (path:/tmp/pip-8rPWUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:13 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:13 INFO - Running setup.py (path:/tmp/pip-KAE9Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:13 INFO - Running setup.py (path:/tmp/pip-VxyTap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:13 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:13 INFO - Running setup.py (path:/tmp/pip-gthkOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:13 INFO - Running setup.py (path:/tmp/pip-R7uJek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:13 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:13 INFO - Running setup.py (path:/tmp/pip-DzVFIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:13 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:13 INFO - Running setup.py (path:/tmp/pip-ubqCJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:13 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)) 15:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:13 INFO - Running setup.py (path:/tmp/pip-gMztzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:14 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:14 INFO - Running setup.py (path:/tmp/pip-hYnXA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:14 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:14 INFO - Running setup.py (path:/tmp/pip-DrKt8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:14 INFO - Running setup.py (path:/tmp/pip-lwTFvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:14 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:14 INFO - Running setup.py (path:/tmp/pip-x8pvyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:14 INFO - Running setup.py (path:/tmp/pip-QKyFJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:14 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:14 INFO - Running setup.py (path:/tmp/pip-NWZwxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:14 INFO - Running setup.py (path:/tmp/pip-9IIzjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:14 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:14 INFO - Running setup.py (path:/tmp/pip-BnOj8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:14 INFO - Running setup.py (path:/tmp/pip-4_Q1ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:14 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)) 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:09:14 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)) 15:09:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:09:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:09:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:16 INFO - Downloading blessings-1.6.tar.gz 15:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:09:16 INFO - Installing collected packages: blessings 15:09:16 INFO - Running setup.py install for blessings 15:09:16 INFO - Successfully installed blessings 15:09:16 INFO - Cleaning up... 15:09:16 INFO - Return code: 0 15:09:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:17 INFO - Reading from file tmpfile_stdout 15:09:17 INFO - Current package versions: 15:09:17 INFO - argparse == 1.2.1 15:09:17 INFO - blessings == 1.6 15:09:17 INFO - blobuploader == 1.2.4 15:09:17 INFO - docopt == 0.6.1 15:09:17 INFO - functools32 == 3.2.3-2 15:09:17 INFO - jsonschema == 2.5.1 15:09:17 INFO - manifestparser == 1.1 15:09:17 INFO - mozInstall == 1.12 15:09:17 INFO - mozcrash == 1.0 15:09:17 INFO - mozdebug == 0.1 15:09:17 INFO - mozdevice == 0.49 15:09:17 INFO - mozfile == 1.2 15:09:17 INFO - mozhttpd == 0.7 15:09:17 INFO - mozinfo == 0.9 15:09:17 INFO - mozleak == 0.1 15:09:17 INFO - mozlog == 3.4 15:09:17 INFO - moznetwork == 0.27 15:09:17 INFO - mozprocess == 0.23 15:09:17 INFO - mozprofile == 0.28 15:09:17 INFO - mozrunner == 6.13 15:09:17 INFO - mozscreenshot == 0.1 15:09:17 INFO - mozsystemmonitor == 0.3 15:09:17 INFO - moztest == 0.8 15:09:17 INFO - mozversion == 1.4 15:09:17 INFO - psutil == 3.1.1 15:09:17 INFO - requests == 1.2.3 15:09:17 INFO - wsgiref == 0.1.2 15:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 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 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:17 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 15:09:17 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 15:09:17 INFO - Using env: {'DISPLAY': ':0', 15:09:17 INFO - 'HOME': '/home/cltbld', 15:09:17 INFO - 'LANG': 'en_US.UTF-8', 15:09:17 INFO - 'LANGUAGE': 'en_US:en', 15:09:17 INFO - 'LOGNAME': 'cltbld', 15:09:17 INFO - 'MAIL': '/var/mail/cltbld', 15:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:17 INFO - 'MOZ_NO_REMOTE': '1', 15:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:17 INFO - 'NO_EM_RESTART': '1', 15:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:17 INFO - 'PWD': '/builds/slave/test', 15:09:17 INFO - 'SHELL': '/bin/bash', 15:09:17 INFO - 'SHLVL': '1', 15:09:17 INFO - 'TERM': 'linux', 15:09:17 INFO - 'TMOUT': '86400', 15:09:17 INFO - 'USER': 'cltbld', 15:09:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:17 INFO - '_': '/tools/buildbot/bin/python'} 15:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 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)) 15:09:17 INFO - Cleaning up... 15:09:17 INFO - Return code: 0 15:09:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1691a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1836c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', '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 15:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:09:17 INFO - Using env: {'DISPLAY': ':0', 15:09:17 INFO - 'HOME': '/home/cltbld', 15:09:17 INFO - 'LANG': 'en_US.UTF-8', 15:09:17 INFO - 'LANGUAGE': 'en_US:en', 15:09:17 INFO - 'LOGNAME': 'cltbld', 15:09:17 INFO - 'MAIL': '/var/mail/cltbld', 15:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:17 INFO - 'MOZ_NO_REMOTE': '1', 15:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:17 INFO - 'NO_EM_RESTART': '1', 15:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:17 INFO - 'PWD': '/builds/slave/test', 15:09:17 INFO - 'SHELL': '/bin/bash', 15:09:17 INFO - 'SHLVL': '1', 15:09:17 INFO - 'TERM': 'linux', 15:09:17 INFO - 'TMOUT': '86400', 15:09:17 INFO - 'USER': 'cltbld', 15:09:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:17 INFO - '_': '/tools/buildbot/bin/python'} 15:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:09:17 INFO - Cleaning up... 15:09:17 INFO - Return code: 0 15:09:17 INFO - Running post-action listener: _resource_record_post_action 15:09:17 INFO - Running post-action listener: _start_resource_monitoring 15:09:17 INFO - Starting resource monitoring. 15:09:17 INFO - [mozharness: 2016-12-29 23:09:17.651975Z] Finished create-virtualenv step (success) 15:09:17 INFO - [mozharness: 2016-12-29 23:09:17.652474Z] Running install step. 15:09:17 INFO - Running pre-action listener: _resource_record_pre_action 15:09:17 INFO - Running main action method: install 15:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:17 INFO - Reading from file tmpfile_stdout 15:09:17 INFO - Detecting whether we're running mozinstall >=1.0... 15:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:09:17 INFO - Reading from file tmpfile_stdout 15:09:17 INFO - Output received: 15:09:17 INFO - Usage: mozinstall [options] installer 15:09:17 INFO - Options: 15:09:17 INFO - -h, --help show this help message and exit 15:09:17 INFO - -d DEST, --destination=DEST 15:09:17 INFO - Directory to install application into. [default: 15:09:17 INFO - "/builds/slave/test"] 15:09:17 INFO - --app=APP Application being installed. [default: firefox] 15:09:17 INFO - mkdir: /builds/slave/test/build/application 15:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:09:35 INFO - Reading from file tmpfile_stdout 15:09:35 INFO - Output received: 15:09:35 INFO - /builds/slave/test/build/application/firefox/firefox 15:09:35 INFO - Running post-action listener: _resource_record_post_action 15:09:35 INFO - [mozharness: 2016-12-29 23:09:35.303352Z] Finished install step (success) 15:09:35 INFO - [mozharness: 2016-12-29 23:09:35.303597Z] Running run-tests step. 15:09:35 INFO - Running pre-action listener: _resource_record_pre_action 15:09:35 INFO - Running main action method: run_tests 15:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:09:35 INFO - Python 2.7.3 15:09:35 INFO - Return code: 0 15:09:35 INFO - grabbing minidump binary from tooltool 15:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 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 0x18343f0>, '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 0x1834cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18351a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:09:35 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 15:09:35 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 15:09:35 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 15:09:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:09:35 INFO - Return code: 0 15:09:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:09:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:09:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:09:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:09:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:09:35 INFO - Using env: {'DISPLAY': ':0', 15:09:35 INFO - 'HOME': '/home/cltbld', 15:09:35 INFO - 'LANG': 'en_US.UTF-8', 15:09:35 INFO - 'LANGUAGE': 'en_US:en', 15:09:35 INFO - 'LOGNAME': 'cltbld', 15:09:35 INFO - 'MAIL': '/var/mail/cltbld', 15:09:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:09:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:35 INFO - 'MOZ_NO_REMOTE': '1', 15:09:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:09:35 INFO - 'NO_EM_RESTART': '1', 15:09:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:35 INFO - 'PWD': '/builds/slave/test', 15:09:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:09:35 INFO - 'SHELL': '/bin/bash', 15:09:35 INFO - 'SHLVL': '1', 15:09:35 INFO - 'TERM': 'linux', 15:09:35 INFO - 'TMOUT': '86400', 15:09:35 INFO - 'USER': 'cltbld', 15:09:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568', 15:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:35 INFO - '_': '/tools/buildbot/bin/python'} 15:09:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:09:35 INFO - mozversion application_buildid: 20161229133006 15:09:35 INFO - mozversion application_changeset: 79ef936724454728beeeba41fb76a61d02c7c226 15:09:35 INFO - mozversion application_display_name: Nightly 15:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:09:35 INFO - mozversion application_name: Firefox 15:09:35 INFO - mozversion application_remotingname: firefox 15:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:09:35 INFO - mozversion application_vendor: Mozilla 15:09:35 INFO - mozversion application_version: 53.0a1 15:09:35 INFO - mozversion platform_buildid: 20161229133006 15:09:35 INFO - mozversion platform_changeset: 79ef936724454728beeeba41fb76a61d02c7c226 15:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:09:35 INFO - mozversion platform_version: 53.0a1 15:09:35 INFO - using testdate: 1483052975 15:09:35 INFO - actual date: 1483052975 15:09:35 INFO - starting webserver on 'localhost:58373' 15:09:35 INFO - SUITE-START | Running 2 tests 15:09:35 INFO - TEST-START | dromaeo_css 15:09:35 INFO - operating with platform_type : linux_ 15:09:35 INFO - Initialising browser for dromaeo_css test... 15:09:35 INFO - TEST-INFO | started process 1740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpskxMPy/profile http://localhost:58373/getInfo.html) 15:09:36 INFO - PROCESS | 1740 | 1483052976074 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:09:39 INFO - PROCESS | 1740 | __metrics Screen width/height:1600/1200 15:09:39 INFO - PROCESS | 1740 | colorDepth:24 15:09:39 INFO - PROCESS | 1740 | Browser inner width/height: 1024/697 15:09:39 INFO - PROCESS | 1740 | __metrics 15:09:39 ERROR - PROCESS | 1740 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 15:09:40 INFO - TEST-INFO | 1740: exit 0 15:09:40 INFO - Browser initialized. 15:09:40 INFO - Running cycle 1/1 for dromaeo_css test... 15:09:40 INFO - TEST-INFO | started process 1865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpskxMPy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:09:52 INFO - PROCESS | 1865 | RSS: Main: 154198016 15:09:52 INFO - PROCESS | 1865 | 15:12:53 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58373/tests/dromaeo/cssquery-ext.html) 15:12:54 INFO - PROCESS | 1865 | RSS: Main: 164687872 15:12:54 INFO - PROCESS | 1865 | 15:14:41 INFO - PROCESS | 1865 | ************************* 15:14:41 INFO - PROCESS | 1865 | A coding exception was thrown and uncaught in a Task. 15:14:41 INFO - PROCESS | 1865 | 15:14:41 INFO - PROCESS | 1865 | Full message: ReferenceError: fetch is not defined 15:14:41 INFO - PROCESS | 1865 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 15:14:41 INFO - PROCESS | 1865 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 15:14:41 INFO - PROCESS | 1865 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 15:14:41 INFO - PROCESS | 1865 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:14:41 INFO - PROCESS | 1865 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:14:41 INFO - PROCESS | 1865 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:14:41 INFO - PROCESS | 1865 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 15:14:41 INFO - PROCESS | 1865 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:14:41 INFO - PROCESS | 1865 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:14:41 INFO - PROCESS | 1865 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:14:41 INFO - PROCESS | 1865 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 15:14:41 INFO - PROCESS | 1865 | 15:14:41 INFO - PROCESS | 1865 | ************************* 15:15:56 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-ext.html (next: http://localhost:58373/tests/dromaeo/cssquery-jquery.html) 15:15:57 INFO - PROCESS | 1865 | RSS: Main: 161964032 15:15:57 INFO - PROCESS | 1865 | 15:18:59 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58373/tests/dromaeo/cssquery-mootools.html) 15:18:59 INFO - PROCESS | 1865 | RSS: Main: 165851136 15:18:59 INFO - PROCESS | 1865 | 15:22:01 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58373/tests/dromaeo/cssquery-prototype.html) 15:22:02 INFO - PROCESS | 1865 | RSS: Main: 162357248 15:22:02 INFO - PROCESS | 1865 | 15:25:04 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58373/tests/dromaeo/cssquery-yui.html) 15:25:05 INFO - PROCESS | 1865 | RSS: Main: 164409344 15:25:05 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:58373/tests/dromaeo/cssquery-yui.html (next: http://localhost:58373/tests/dromaeo/cssquery-dojo.html) 15:28:08 INFO - PROCESS | 1865 | RSS: Main: 166080512 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | __start_tp_report 15:28:08 INFO - PROCESS | 1865 | _x_x_mozilla_page_load 15:28:08 INFO - PROCESS | 1865 | _x_x_mozilla_page_load_details 15:28:08 INFO - PROCESS | 1865 | |i|pagename|runs| 15:28:08 INFO - PROCESS | 1865 | |0;dojo.html;3209.5667084943534;3251.4309995750755;3256.7883087599303;3284.3102948380847;3296.9010929672118;5281.656692314997;5535.833924982252;5558.638688485245;5565.554755619551;5567;5311.57507399408;5389.434109418504;5434.701091439969;5457.508824205821;5498.4501549845;4851.393575803016;4869.853904382874;4908.656394052417;4980.651354405193;4998.77505512251;4756.405449318835;5000.049990501811;5062.569681577071;5080.491950804909;5084.237364395333;4812.807487700487;4949.257611358289;4971.676841005359;4982.078315511624;5051.621128415365;4833.4441539222735;4834.226523756217;4848.5151484851585;4904.730239836807;4987.501249875019;4824.758762061918;4872.4883887192045;4879.658423910328;4908.361912951329;4908.7300198489065;5334.4398838122215;5354.089804733205;5365.597580181502;5372.382176049726;5441.863953401196;5722.341930677983;5770.596058275923;5809.593328467009;5852.648841069549;5863.912041319383;5960.940390596082;5960.940390596125;5961.5230781537375;5963.52291816658;6011.849703757441;5472.343318801769;5528.612997090206;5531.889362212773;5537.335519737658;5537.446255374511;5871.677057761779;5921.881562368729;5924.496417804448;5955.970220148958;6104.26748790148;5649.350324712628;5676.318841738936;5710.828675139669;5741.282339707536;5757.942420575824;5756.194132821408;5769.105788602773;5783.161441590946;5811.15738217956;5828.562857785683;5661.716914154275;5722.942770572241;5845.795397161164;5873.765049398062;5887.970560147171;5919.052951527735;5928.288605367384;5936.495397891227;5938.732757025944;5984.521238300926;4423.424954755926;4445.333200020023;4446.177457170434;4450.06548624786;4484.0583477469445;3886.2810380079777;3904.492415985904;3908.257431088141;3919.2553414851086;3944.9408258876138;5573.359063707644;5588.776448941997;5632.436756324335;5658.462446067617;5723.828285151452;5869.295684517885;5893.057110862242;5910.7931222407005;5959.553033522503;5965.433283838029;5375.381831089475;5401.594880383908;5564.081926482166;5595.468430499178;5600.803971861047;4893.290472881412;4912.631552633496;4924.43369012561;4925.310456536088;4956.4547899731;6163.322034576193;6194.5044396448175;6227.564070515111;6227.657478838617;6257.68711564429;5730.799422020209;5762.855928601818;5960.5231581474245;5966.940330596812;5971;6068.393160683896;6085.57400981927;6116.853194528166;6118.112873633209;6151.169592105188;5668.37647858727;5715.514181294637;5724.713764311685;5748.45389687979;5786.797462088806;5770.105633626795;5817.47642712141;5827.3007239131575;5870.207521984477;5882.441168089024;5656.236408084856;5736;5758.568107391879;5794.159846822103;5866.560007999503;5819.185314055951;5830.096335068071;5831.35855055935;5834.15404766298;5851.765929362778;5635.605507614591;5640.943590564042;5834.82495525118;5839.970800146142;5843.766249349978;5749.338826035059;5792.420757924174;5853.64878107315;5865.94134058654;5928.79249226275;5668.178114173339;5707.6290041147195;5713.057345538106;5714.228579141763;5715.057015592381 15:28:08 INFO - PROCESS | 1865 | |1;ext.html;550.9586780991425;558.6424688199551;566.3289002532;573.088788825767;573.3893403525246;40091.198176036465;41868.23185913267;42197.78901105494;45169;45236;43364.349534756926;43480.782596087054;43490;43571;43596.564034359646;8905.821883562341;9065.818683626356;9070.27437804974;9105.95447022764;10041.246906482043;10067.89932100677;10280.12618927392;10313.793724125513;10366.170706343511;10376.688699339033;10296.33073850201;10485.213608979318;10486.737831554196;10487.842682359733;10497.37015779052;10302.278840481169;10390.896091039069;10411.583536658525;10415.791684166312;10429.217808664305;10048.79902401948;10096.848547271797;10175.13511351536;10219.284650074498;10285.537150828231;37229.81385093084;37315.44026839599;37437.43843842344;37567.62432375669;37814;4033.1328764315813;4036.091879327157;4108.137291168858;4177.603127702894;4187.497500299984;28513.287167820763;28659.57010644842;28780.136595901946;29003.274918127216;29571;4252.192083504125;4316.266234740102;4348.7390756554705;4363.799283154147;4390.8902277443;311728;312954;313073;313383;316293;50935.63304586927;50983.09761986898;51217.23174152391;51358;51393.743031285354;53381.733091334296;53542.732286338316;53761.731191343795;53856.46143538593;53870.191947120824;15218.467353642569;15250.161241131622;15381.384744610314;15396.923015384851;15432.614184645248;10833;11010.284331518425;11698.181127321175;11735.23720958135;11766.235194712317;16682.58293542671;16710.498685039467;16715.247813848408;16748.399094194807;16786.160691965353;6511.674416279167;6577.539572229898;6595.538312318187;6819.863602727918;6882.7591034313555;906.1527471813876;916.4501299220415;919.2921450483224;923.2521657457481;963.6241865672393;29028.854855725585;29376;29423.264418389284;30776.53835192474;30837.383252335276;1050.6900464363032;1068.6847380022912;1072.2279958429917;1097.2100087936674;1188.9940550297192;15455.690886182214;16173.514794556182;16268.349266029227;16319.592010199602;16469.752953705705;455123;456638;456814;457221;458252;56955.43044569501;57248.42751572514;57822.7108864453;57877.71061144752;58211.70894145587;1333.5199328241824;1384.3977869626744;1385.4942945824903;1390.1381143690976;1390.2631605249235;28396.43207135887;29082.27294317659;29944.700552995062;29958.25104372408;30314.242143946576;48039.51960480351;48978.26532602085;49357.506424935294;49560;49601.75199123981;381884;381983;382022;383939;384825;25053.74946250514;25421;25452.490950180523;26338.707661542307;27281;41294.1741165169;41483.37774933318;41555;41722.58277417187;42333;33459.665403346626;35144.64855351516;35419.468707969914;35558.46662300016;35625.46561801523;34796.478052829734;34892.65107348894;34956.47565286472;35172.29655406929;35577.288454230256;34874.30251394907;35339;35358.292834143685;35438.82280588581;36031.27937441289;30182;30891.69108308888;32221.838890805397;32413;32425.35149297048;24276.635850461906;24596.754032459445;24777.62833557462;24800;24994.250172494852 15:28:08 INFO - PROCESS | 1865 | |2;jquery.html;388.28749245135174;388.79588216186517;402.47678018575846;412.3217307529115;424.7387856468271;14201;17205.569860753487;18250.452486425394;18685.159167837464;18704;18278;18721.531961700963;18848.717269240937;19037.904810475928;19192.328268510606;5170.844874653766;5341.946580534203;5405.297311349539;5418.160185171305;5439.048166570854;5326.6005049621435;5375.731213439312;5389.245505629215;5403.054465468547;5710.885782284352;5950;6074.210352654149;6090.208272924514;6096.69516524172;6116.908246376286;6067.787627433041;6070.059140833156;6141.232345956733;6511.804645860631;6548.508861835334;5843.474087332161;5852.67810270435;5857.589968702193;5865.266841644794;5865.677387743673;13993.790093148611;14075.788863167061;14196.290185490685;14207.715845683131;17069.91465042667;2197.648376259807;2200.9655461932866;2201.2295696506253;2214.8892555372167;2239.596872562938;10199.796004079952;10411.167106631452;10445.216608754374;11454.427278636118;14983.400663973536;1902.3436914264557;1982.3854605072474;1992.9202831886853;2000.089959068635;2003.889786061766;78487.21512784914;78533;79115.41769164584;79956;80323;10902.400367979679;10974.341539507655;11078.335299882034;11191.496382662799;11323;15345.232738363036;15388.153651549048;15439.764818814469;15575.454859079879;15598.298076586354;7491.250874912575;7593.31660150578;7859.214078592095;7954.284114429729;8001.199880011952;7143.1428228612895;7175.103112110986;7270.345668889825;7445.329920307198;7448.73929412468;4855.587275081587;4855.902881942341;4873.95126048735;4884.291777692215;4921.532454416824;2037.8064083912004;2059.9588008240053;2072.658011428128;2087.00514761318;2097;1009.1724785675822;1028.5937054863286;1048.7325731938306;1070.1385384765338;1094.1137678480457;18119.637607248045;18368.908155459136;18582.25670973146;18907.621847562972;18993.62012759737;1016.4917541229386;1030.3766221436074;1040.7606250562412;1043.7286305560608;1064.7870425914693;10093.798124037477;10592.89407105919;10603.09873660732;10703.892961070289;10811.081058109992;324161;324934;326866;407511;407726.8068298316;92151.53924230336;92867.53566232261;94320;94656.52671736597;94791;1004.4324956399611;1016.4866742295094;1058.3861360411097;1061.570064124031;1069.2408390043156;17047.40334088301;17136.40022599203;17576.91211543934;17637.26622373534;17667.55831104233;40519.3922091163;41270;41457.58542414537;41533.58466415418;41695.374569380874;52288.65938172933;55397;56236;57231;58265.41734582769;8565.143485651384;8647.87028194565;8867.201951824367;8951.597178127131;9050.592723327334;12508.249505029728;12874.227546347249;13130.803037954247;13199.472021119047;13393.732125357634;10780.784384312426;11178.217524773188;11432.371219583003;11589.594364197214;12278.386080696107;11488.310701357945;11602.825957610812;11638.418079095842;11654.766904662029;11668.649940501798;11685.415729213335;11865.466054027416;11881.405929703653;11945;12126.939365303117;7491.1385190701585;7676.961615191888;8762.386632935633;8871.334649901155;8877;11283.774324513628;11546.82679759821;11739.823902641296;12011.63965081049;12230.388480576114 15:28:08 INFO - PROCESS | 1865 | |3;mootools.html;1375.8280214973129;1410.9365078571466;1413.1379858286443;1417.3551034279403;1417.4613646814212;8118.553479558622;8491.830163396728;8590.95704521478;8640.697575584856;8710.60802263898;8619.353548483858;8801.55992200389;8847.071057538962;8869;8893.466392016468;2353.329301149173;2367.739548649647;2369.6208606622945;2382.094803974487;2389.8685572293602;2102.926397576085;2168.5012447137087;2287.0737351372723;2379.583572874749;2397.976020239793;2377.6909001829827;2413.5293617744546;2417.093589903786;2423.994042472372;2424.3939015246187;2249.9437514062115;2252.9523771446275;2266.5013696986634;2296.4488522754586;2301.9884900575476;2341.9695334052963;2344.284993077111;2344.48421347305;2346.9295921122393;2386.570417324881;8651.826963460695;8692.913070869274;8748.25639820616;8765.561721913879;8800.515971621558;5215.322008138955;5333.679979201222;5338.706371149585;5362.1420572708175;5372.892542149135;3365.9831700841582;3557.7865328080397;3584.1039740064984;3635.3456377851976;3663.9084022899374;1399.3842709207916;1449.6013596261007;1463.290304202461;1474.6460849396067;1514.3336931749996;11252.099832013419;11279;11296.378699171542;11327.433628318551;11341.886581134166;4321.3301938199;4462.174497717932;4532.4334458192725;4571.634269258451;4638;9270.119338662951;9309.069093090637;9405.247580193705;9417.482038487811;9429.952850235704;5190.325257716473;5286.788528458818;5307.336582927134;5317.73411329432;5323.201519772065;4891.779869905863;4903.387076615423;4918.5573298403315;4922.606191504671;4926.285688575136;3643.0892276930767;3659.9817000914827;3697.6302369762807;3719.9442008369897;3879.7478163919254;1097.4951522299673;1118.2284223885492;1148.7702459508132;1327.595083499542;1361.877431031212;933.8459154239611;957.2437774158337;957.9329446938648;960.5533426956499;973.4986481961795;5320.627556071116;5777.075667893117;5787.392323806024;5804.593678442553;5911.970440147772;913.3150137396952;962.2398305338844;968.2496065549255;1005;1014.1481155829139;3929.724919255682;3992.2215168041953;4129.442525259112;4148.336266197454;4178.415021896937;10195.541200645988;10466.63366782159;10544.051035406697;10545.103666188306;10577;10167.23745719074;10323.690289291333;10346.965303469593;10357.274990750726;10361.792764144826;7890;8094.878576821352;8113.391495637851;8135.227153420416;8164.673413063411;5827.970860145672;5847.766089356378;5868.67722275279;5901.793437229848;5958.940410595838;9525.714228573153;9580.377275477073;9602.471864047544;9611.711648650551;9638.277129215197;9070.81858362816;9230.861537077084;9385.20225780789;9568.043195680377;9630.951845240728;7516.060492438445;7698.0762308523335;7712.2647404333575;7712.305892469481;7723.729669461542;7837.647305871364;7839.725609603636;7908.960455197687;7922.524648521107;7966.3626909848235;6478.509942713296;7081.504294699527;7095.8935615964765;7213.6032518212005;7499;7343.742968996059;7364.484486086137;7399.408047356091;7442.4418168636485;7540.622968851645;6474.611523308617;6534.542582019213;6540.869182616226;6569.770058047945;6625.370589793962;5122.154844450624;5143.177091665316;5150.819721309736;5156.510131537648;5237.0835103857435;5031.320771695774;5116.386033675983;5168.8449346519665;5206.062908676475;5257.2639830423 15:28:08 INFO - PROCESS | 1865 | |4;prototype.html;385.1046317312249;393.8503368719886;397.8289335585697;413.23138961531566;417.5782459715686;5111.309973153632;5243.239730239123;5297.814576489824;5318.813841515548;5369.603902985223;5162.92255616166;5243.344581927249;5249.711265880384;5252.842414727555;5281.762320695569;4226.640735537483;4227.239096962546;4239.872803815891;4243.809028593705;4254.6170844623975;4093.856715014975;4244.363345498169;4255.6380459437005;4277;4311;4182.22628813669;4194.035371864473;4195.643370313527;4210.557891421403;4228.76741779202;4158.2307273154565;4190.832366705344;4208.158368326316;4211.410402543646;4223.282042052859;3823.7323387362894;3824.3307421201457;3844.5770965193806;3845.3847384418636;3845.557760857509;5082.263071854597;5123.743812809382;5142.1772516397195;5180.4042535108565;5197.674592978766;4813.735244561548;4876.414830220361;4876.731779752147;4876.902461950776;4894.388201474816;5476.644018138808;5543.362513310981;5563.638363506399;5576.665400076008;5590.468905453975;4182.728122672017;4185.844290190555;4193.7903104844945;4214.220369231672;4221.430106935555;5738.167965645;5749.913751293734;5760.135979603051;5781.075027995501;5819.53443724501;4188.141431006637;4286.592773686495;4316.611504964569;4324.243257429937;4336.739795612273;4299.54854740254;4335.28467802809;4344.6089851913475;4393.1872603568445;4398.296272596369;4835.153848076572;4868.294097355863;4887.29134275528;4898.069366820293;4915.557599816034;5515.420880807537;5596.2152359731845;5610.49505544503;5618.887622247532;5626;1456.3519233941006;1475.970480590382;1490.9254537273093;1497.071815474413;1500;1023.3399457349967;1076.9445942975929;1083.4236186348844;1085.46812062089;1094.6661268313242;5132.615053870974;5134.05020071288;5154.690718556899;5180.611454140954;5182.97408512955;5389.730513474311;5456.972715136399;5524.309461317335;5534.754680197004;5543.916841247385;5093.439721630692;5193.533071790869;5207.244949482304;5238.7904483820985;5255.5795536357;2893.7540309073543;2898.4058268054996;2907.7383035526905;2931.589577459157;2937.8237305761722;5647.322321321386;5780.768769249183;5781.71091445426;5793.710314484259;5806.21616081832;5441.6190866639745;5588.0779671354585;5620.859478512988;5634.774609015675;5637.436256374412;5821.708914554255;5821.94178058214;5822;5845.7369418376275;5876.882462350814;5331.466853314638;5409.945900541102;5458.972705136449;5469.945300546943;5698.715064246689;5635.633683810539;5661.348944871228;5695.288088988877;5705.942940570707;5717.771289148387;5948.196993405835;5979.847254988288;6027.065804800263;6069.666168360783;6101.816945491642;4032.1371826003865;4136.242097108657;4167;4179.05376709413;4180.456540649696;5751.396103409082;5779.768809247583;5810.854728631818;5830.2129212555765;5836.503897168618;5499.945000549943;5686.459786320293;5709.4576015280145;5715.17130016137;5719.3136823581435;5605.5795815313195;5613.698383662679;5626.831195064155;5646.463585959327;5651.321841379061;5616.578756593353;5625.268715067015;5645.491905728504;5645.927273817962;5672.574556908331;5306.734663266899;5542.501174894118;5618.831435056954;5670.971645141913;5672.914906276492;5444.537214336826;5482.314710661168;5494.011078005838;5496.835094947158;5507.779688812403 15:28:08 INFO - PROCESS | 1865 | |5;yui.html;741.340207215578;769.4806005945985;787.5747096567853;809.3727361294996;823.4112609484217;14671.926640366797;15064;15069.095854248753;15638.765418518717;16131.354745810168;14783;14907.701845963102;15170.54488365348;15188.31652575634;15346.079235245865;801.8636831738589;815.7960509872547;836.9832603347929;843.464400105931;845.0619812008665;835.7242110103651;841.3899922556159;842.3471809347743;843.0178841649471;843.1947490146925;867.5878957495171;868.5570359116833;868.7828042989253;871.5293741379647;871.7428358634187;871.372611719561;873.5413907698446;876.176394189463;876.9123087691244;877.4209022045451;642.958207716497;644.5455953552735;645.4674893213117;645.7384759172543;646.2116218213772;16255.349786008665;16348.427805026886;16395.672086558323;16488;16677.249523771337;14934.850651493563;15086.321115549827;15146.848531514654;15338.693226135527;15469.613259668598;12297.508099676003;12585.119041667087;12598.370081495888;12655.43050562727;12703.428345724373;3786.318462676717;3842.6733727632904;3872.8257228424786;3882.864099756495;3903.1413089120347;87200.5639971796;88192;90231.09768902358;90257.54871225667;90929.09070909339;10891.03961286976;10936.781264374828;11007.779844403067;11046.834297485544;11097.1122310215;21983.780162198495;22011.449713756963;22120.336389908327;22246.88876555607;22258.22096226624;7979.241972012649;8219.136990616018;8294.419390642597;8326.50040997542;8384.413091083565;4530.29780384041;4582.312653102062;4611.953880461152;4613.63090952723;4764.714117152982;3590.533230680047;3642.927141457209;3673.228621989412;3673.6142705016123;3704.1665625234104;626.7931582577829;627.1783963008473;640.38203133975;645.2869579115104;647.6049609738056;732.0484461329359;756.1493320014984;761.4137114421865;778.8559116563454;784.1310123319647;9739.41563506192;9771.364861283993;10389.220808439397;10421.739456513496;10529.631462898762;611.126089691745;627.1188979483742;628.7045088808253;630.3759278314436;633.8795628830508;4458.398116254264;4622.560856718606;4687;4717.905641887143;4733.881652958635;68534.65732671304;76899;78131.21868781239;78427;81955.5902220497;70831.64584177149;72336.63831680808;72636.91044634335;72753;74625.25374746183;49534.532582718864;60829;61767.382326176164;61919.38080619226;62927.37072629215;8875.289976801969;9090.681826136053;9121.133492318218;9205.539723013957;9229.630814767334;43657.34513982356;44265.557344427434;44401.11197776091;45302.773486132355;46784;47007.29489057735;47662.28506572473;48527.51472485371;52672.20991685204;55029.174562382395;13075.280859552817;13184.143030702973;13493.797593036308;13595.524156654865;13964.4414223434;14605.488807892098;14816.481423150139;15694.2937567812;16143.434979775651;16184.757228641345;13829;14433.639159021108;14720.484783032542;14729;14905.62735931567;10877.67366978992;13503;13658.248796316298;13842.930785346009;13937.651558711113;13052.34738263064;13750.65623359424;13944.16335019902;14066.648333791327;14265.928670356998;11700.531978720755;11718.355490448108;12251.387430628254;12352.444140013542;12580.119391642496;11190.84204820659;11251.15616328795;11431.542738290374;11594.884051159717;11607.535698572301 15:28:08 INFO - PROCESS | 1865 | __end_tp_report 15:28:08 INFO - PROCESS | 1865 | __start_cc_report 15:28:08 INFO - PROCESS | 1865 | _x_x_mozilla_cycle_collect,319 15:28:08 INFO - PROCESS | 1865 | __end_cc_report 15:28:08 INFO - PROCESS | 1865 | __startTimestamp1483054088352__endTimestamp 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | ------- Summary: start ------- 15:28:08 INFO - PROCESS | 1865 | Number of tests: 6 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#0] dojo.html Cycles:165 Average:5444.91 Median:5707.63 stddev:638.89 (11.2%) stddev-sans-first:616.31 15:28:08 INFO - PROCESS | 1865 | Values: 3209.6 3251.4 3256.8 3284.3 3296.9 5281.7 5535.8 5558.6 5565.6 5567.0 5311.6 5389.4 5434.7 5457.5 5498.5 4851.4 4869.9 4908.7 4980.7 4998.8 4756.4 5000.0 5062.6 5080.5 5084.2 4812.8 4949.3 4971.7 4982.1 5051.6 4833.4 4834.2 4848.5 4904.7 4987.5 4824.8 4872.5 4879.7 4908.4 4908.7 5334.4 5354.1 5365.6 5372.4 5441.9 5722.3 5770.6 5809.6 5852.6 5863.9 5960.9 5960.9 5961.5 5963.5 6011.8 5472.3 5528.6 5531.9 5537.3 5537.4 5871.7 5921.9 5924.5 5956.0 6104.3 5649.4 5676.3 5710.8 5741.3 5757.9 5756.2 5769.1 5783.2 5811.2 5828.6 5661.7 5722.9 5845.8 5873.8 5888.0 5919.1 5928.3 5936.5 5938.7 5984.5 4423.4 4445.3 4446.2 4450.1 4484.1 3886.3 3904.5 3908.3 3919.3 3944.9 5573.4 5588.8 5632.4 5658.5 5723.8 5869.3 5893.1 5910.8 5959.6 5965.4 5375.4 5401.6 5564.1 5595.5 5600.8 4893.3 4912.6 4924.4 4925.3 4956.5 6163.3 6194.5 6227.6 6227.7 6257.7 5730.8 5762.9 5960.5 5966.9 5971.0 6068.4 6085.6 6116.9 6118.1 6151.2 5668.4 5715.5 5724.7 5748.5 5786.8 5770.1 5817.5 5827.3 5870.2 5882.4 5656.2 5736.0 5758.6 5794.2 5866.6 5819.2 5830.1 5831.4 5834.2 5851.8 5635.6 5640.9 5834.8 5840.0 5843.8 5749.3 5792.4 5853.6 5865.9 5928.8 5668.2 5707.6 5713.1 5714.2 5715.1 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#1] ext.html Cycles:180 Average:53769.05 Median:35050.56 stddev:102717.62 (293.1%) stddev-sans-first:102928.05 15:28:08 INFO - PROCESS | 1865 | Values: 551.0 558.6 566.3 573.1 573.4 40091.2 41868.2 42197.8 45169.0 45236.0 43364.3 43480.8 43490.0 43571.0 43596.6 8905.8 9065.8 9070.3 9106.0 10041.2 10067.9 10280.1 10313.8 10366.2 10376.7 10296.3 10485.2 10486.7 10487.8 10497.4 10302.3 10390.9 10411.6 10415.8 10429.2 10048.8 10096.8 10175.1 10219.3 10285.5 37229.8 37315.4 37437.4 37567.6 37814.0 4033.1 4036.1 4108.1 4177.6 4187.5 28513.3 28659.6 28780.1 29003.3 29571.0 4252.2 4316.3 4348.7 4363.8 4390.9 311728.0 312954.0 313073.0 313383.0 316293.0 50935.6 50983.1 51217.2 51358.0 51393.7 53381.7 53542.7 53761.7 53856.5 53870.2 15218.5 15250.2 15381.4 15396.9 15432.6 10833.0 11010.3 11698.2 11735.2 11766.2 16682.6 16710.5 16715.2 16748.4 16786.2 6511.7 6577.5 6595.5 6819.9 6882.8 906.2 916.5 919.3 923.3 963.6 29028.9 29376.0 29423.3 30776.5 30837.4 1050.7 1068.7 1072.2 1097.2 1189.0 15455.7 16173.5 16268.3 16319.6 16469.8 455123.0 456638.0 456814.0 457221.0 458252.0 56955.4 57248.4 57822.7 57877.7 58211.7 1333.5 1384.4 1385.5 1390.1 1390.3 28396.4 29082.3 29944.7 29958.3 30314.2 48039.5 48978.3 49357.5 49560.0 49601.8 381884.0 381983.0 382022.0 383939.0 384825.0 25053.7 25421.0 25452.5 26338.7 27281.0 41294.2 41483.4 41555.0 41722.6 42333.0 33459.7 35144.6 35419.5 35558.5 35625.5 34796.5 34892.7 34956.5 35172.3 35577.3 34874.3 35339.0 35358.3 35438.8 36031.3 30182.0 30891.7 32221.8 32413.0 32425.4 24276.6 24596.8 24777.6 24800.0 24994.3 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#2] jquery.html Cycles:180 Average:24990.45 Median:2048.88 stddev:60425.08 (2949.2%) stddev-sans-first:60566.35 15:28:08 INFO - PROCESS | 1865 | Values: 388.3 388.8 402.5 412.3 424.7 14201.0 17205.6 18250.5 18685.2 18704.0 18278.0 18721.5 18848.7 19037.9 19192.3 5170.8 5341.9 5405.3 5418.2 5439.0 5326.6 5375.7 5389.2 5403.1 5710.9 5950.0 6074.2 6090.2 6096.7 6116.9 6067.8 6070.1 6141.2 6511.8 6548.5 5843.5 5852.7 5857.6 5865.3 5865.7 13993.8 14075.8 14196.3 14207.7 17069.9 2197.6 2201.0 2201.2 2214.9 2239.6 10199.8 10411.2 10445.2 11454.4 14983.4 1902.3 1982.4 1992.9 2000.1 2003.9 78487.2 78533.0 79115.4 79956.0 80323.0 10902.4 10974.3 11078.3 11191.5 11323.0 15345.2 15388.2 15439.8 15575.5 15598.3 7491.3 7593.3 7859.2 7954.3 8001.2 7143.1 7175.1 7270.3 7445.3 7448.7 4855.6 4855.9 4874.0 4884.3 4921.5 2037.8 2060.0 2072.7 2087.0 2097.0 1009.2 1028.6 1048.7 1070.1 1094.1 18119.6 18368.9 18582.3 18907.6 18993.6 1016.5 1030.4 1040.8 1043.7 1064.8 10093.8 10592.9 10603.1 10703.9 10811.1 324161.0 324934.0 326866.0 407511.0 407726.8 92151.5 92867.5 94320.0 94656.5 94791.0 1004.4 1016.5 1058.4 1061.6 1069.2 17047.4 17136.4 17576.9 17637.3 17667.6 40519.4 41270.0 41457.6 41533.6 41695.4 52288.7 55397.0 56236.0 57231.0 58265.4 8565.1 8647.9 8867.2 8951.6 9050.6 12508.2 12874.2 13130.8 13199.5 13393.7 10780.8 11178.2 11432.4 11589.6 12278.4 11488.3 11602.8 11638.4 11654.8 11668.6 11685.4 11865.5 11881.4 11945.0 12126.9 7491.1 7677.0 8762.4 8871.3 8877.0 11283.8 11546.8 11739.8 12011.6 12230.4 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#3] mootools.html Cycles:180 Average:5614.90 Median:5210.69 stddev:3065.29 (58.8%) stddev-sans-first:3057.33 15:28:08 INFO - PROCESS | 1865 | Values: 1375.8 1410.9 1413.1 1417.4 1417.5 8118.6 8491.8 8591.0 8640.7 8710.6 8619.4 8801.6 8847.1 8869.0 8893.5 2353.3 2367.7 2369.6 2382.1 2389.9 2102.9 2168.5 2287.1 2379.6 2398.0 2377.7 2413.5 2417.1 2424.0 2424.4 2249.9 2253.0 2266.5 2296.4 2302.0 2342.0 2344.3 2344.5 2346.9 2386.6 8651.8 8692.9 8748.3 8765.6 8800.5 5215.3 5333.7 5338.7 5362.1 5372.9 3366.0 3557.8 3584.1 3635.3 3663.9 1399.4 1449.6 1463.3 1474.6 1514.3 11252.1 11279.0 11296.4 11327.4 11341.9 4321.3 4462.2 4532.4 4571.6 4638.0 9270.1 9309.1 9405.2 9417.5 9430.0 5190.3 5286.8 5307.3 5317.7 5323.2 4891.8 4903.4 4918.6 4922.6 4926.3 3643.1 3660.0 3697.6 3719.9 3879.7 1097.5 1118.2 1148.8 1327.6 1361.9 933.8 957.2 957.9 960.6 973.5 5320.6 5777.1 5787.4 5804.6 5912.0 913.3 962.2 968.2 1005.0 1014.1 3929.7 3992.2 4129.4 4148.3 4178.4 10195.5 10466.6 10544.1 10545.1 10577.0 10167.2 10323.7 10347.0 10357.3 10361.8 7890.0 8094.9 8113.4 8135.2 8164.7 5828.0 5847.8 5868.7 5901.8 5958.9 9525.7 9580.4 9602.5 9611.7 9638.3 9070.8 9230.9 9385.2 9568.0 9631.0 7516.1 7698.1 7712.3 7712.3 7723.7 7837.6 7839.7 7909.0 7922.5 7966.4 6478.5 7081.5 7095.9 7213.6 7499.0 7343.7 7364.5 7399.4 7442.4 7540.6 6474.6 6534.5 6540.9 6569.8 6625.4 5122.2 5143.2 5150.8 5156.5 5237.1 5031.3 5116.4 5168.8 5206.1 5257.3 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#4] prototype.html Cycles:180 Average:4718.81 Median:5241.02 stddev:1338.86 (25.5%) stddev-sans-first:1302.51 15:28:08 INFO - PROCESS | 1865 | Values: 385.1 393.9 397.8 413.2 417.6 5111.3 5243.2 5297.8 5318.8 5369.6 5162.9 5243.3 5249.7 5252.8 5281.8 4226.6 4227.2 4239.9 4243.8 4254.6 4093.9 4244.4 4255.6 4277.0 4311.0 4182.2 4194.0 4195.6 4210.6 4228.8 4158.2 4190.8 4208.2 4211.4 4223.3 3823.7 3824.3 3844.6 3845.4 3845.6 5082.3 5123.7 5142.2 5180.4 5197.7 4813.7 4876.4 4876.7 4876.9 4894.4 5476.6 5543.4 5563.6 5576.7 5590.5 4182.7 4185.8 4193.8 4214.2 4221.4 5738.2 5749.9 5760.1 5781.1 5819.5 4188.1 4286.6 4316.6 4324.2 4336.7 4299.5 4335.3 4344.6 4393.2 4398.3 4835.2 4868.3 4887.3 4898.1 4915.6 5515.4 5596.2 5610.5 5618.9 5626.0 1456.4 1476.0 1490.9 1497.1 1500.0 1023.3 1076.9 1083.4 1085.5 1094.7 5132.6 5134.1 5154.7 5180.6 5183.0 5389.7 5457.0 5524.3 5534.8 5543.9 5093.4 5193.5 5207.2 5238.8 5255.6 2893.8 2898.4 2907.7 2931.6 2937.8 5647.3 5780.8 5781.7 5793.7 5806.2 5441.6 5588.1 5620.9 5634.8 5637.4 5821.7 5821.9 5822.0 5845.7 5876.9 5331.5 5409.9 5459.0 5469.9 5698.7 5635.6 5661.3 5695.3 5705.9 5717.8 5948.2 5979.8 6027.1 6069.7 6101.8 4032.1 4136.2 4167.0 4179.1 4180.5 5751.4 5779.8 5810.9 5830.2 5836.5 5499.9 5686.5 5709.5 5715.2 5719.3 5605.6 5613.7 5626.8 5646.5 5651.3 5616.6 5625.3 5645.5 5645.9 5672.6 5306.7 5542.5 5618.8 5671.0 5672.9 5444.5 5482.3 5494.0 5496.8 5507.8 15:28:08 INFO - PROCESS | 1865 | 15:28:08 INFO - PROCESS | 1865 | [#5] yui.html Cycles:180 Average:17947.59 Median:4612.79 stddev:23049.45 (499.7%) stddev-sans-first:23077.90 15:28:08 INFO - PROCESS | 1865 | Values: 741.3 769.5 787.6 809.4 823.4 14671.9 15064.0 15069.1 15638.8 16131.4 14783.0 14907.7 15170.5 15188.3 15346.1 801.9 815.8 837.0 843.5 845.1 835.7 841.4 842.3 843.0 843.2 867.6 868.6 868.8 871.5 871.7 871.4 873.5 876.2 876.9 877.4 643.0 644.5 645.5 645.7 646.2 16255.3 16348.4 16395.7 16488.0 16677.2 14934.9 15086.3 15146.8 15338.7 15469.6 12297.5 12585.1 12598.4 12655.4 12703.4 3786.3 3842.7 3872.8 3882.9 3903.1 87200.6 88192.0 90231.1 90257.5 90929.1 10891.0 10936.8 11007.8 11046.8 11097.1 21983.8 22011.4 22120.3 22246.9 22258.2 7979.2 8219.1 8294.4 8326.5 8384.4 4530.3 4582.3 4612.0 4613.6 4764.7 3590.5 3642.9 3673.2 3673.6 3704.2 626.8 627.2 640.4 645.3 647.6 732.0 756.1 761.4 778.9 784.1 9739.4 9771.4 10389.2 10421.7 10529.6 611.1 627.1 628.7 630.4 633.9 4458.4 4622.6 4687.0 4717.9 4733.9 68534.7 76899.0 78131.2 78427.0 81955.6 70831.6 72336.6 72636.9 72753.0 74625.3 49534.5 60829.0 61767.4 61919.4 62927.4 8875.3 9090.7 9121.1 9205.5 9229.6 43657.3 44265.6 44401.1 45302.8 46784.0 47007.3 47662.3 48527.5 52672.2 55029.2 13075.3 13184.1 13493.8 13595.5 13964.4 14605.5 14816.5 15694.3 16143.4 16184.8 13829.0 14433.6 14720.5 14729.0 14905.6 10877.7 13503.0 13658.2 13842.9 13937.7 13052.3 13750.7 13944.2 14066.6 14265.9 11700.5 11718.4 12251.4 12352.4 12580.1 11190.8 11251.2 11431.5 11594.9 11607.5 15:28:08 INFO - PROCESS | 1865 | -------- Summary: end -------- 15:28:08 INFO - PROCESS | 1865 | 15:28:09 INFO - TEST-INFO | 1865: exit 0 15:28:09 INFO - TEST-OK | dromaeo_css | took 1113583ms 15:28:09 INFO - TEST-START | kraken 15:28:09 INFO - operating with platform_type : linux_ 15:28:09 INFO - Initialising browser for kraken test... 15:28:09 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj33YfH/profile http://localhost:58373/getInfo.html) 15:28:09 INFO - PROCESS | 2222 | 1483054089629 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:28:12 INFO - PROCESS | 2222 | __metrics Screen width/height:1600/1200 15:28:12 INFO - PROCESS | 2222 | colorDepth:24 15:28:12 INFO - PROCESS | 2222 | Browser inner width/height: 1024/697 15:28:12 INFO - PROCESS | 2222 | __metrics 15:28:13 ERROR - PROCESS | 2222 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 15:28:14 INFO - TEST-INFO | 2222: exit 0 15:28:14 INFO - Browser initialized. 15:28:14 INFO - Running cycle 1/1 for kraken test... 15:28:14 INFO - TEST-INFO | started process 2344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj33YfH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:28:26 INFO - PROCESS | 2344 | RSS: Main: 154640384 15:28:26 INFO - PROCESS | 2344 | 15:28:28 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58373/tests/kraken/driver.html?testName=audio-beat-detection) 15:28:29 INFO - PROCESS | 2344 | RSS: Main: 160550912 15:28:29 INFO - PROCESS | 2344 | 15:28:32 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58373/tests/kraken/driver.html?testName=audio-dft) 15:28:32 INFO - PROCESS | 2344 | RSS: Main: 159326208 15:28:32 INFO - PROCESS | 2344 | 15:28:35 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58373/tests/kraken/driver.html?testName=audio-fft) 15:28:36 INFO - PROCESS | 2344 | RSS: Main: 156151808 15:28:36 INFO - PROCESS | 2344 | 15:28:38 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58373/tests/kraken/driver.html?testName=audio-oscillator) 15:28:39 INFO - PROCESS | 2344 | RSS: Main: 156930048 15:28:39 INFO - PROCESS | 2344 | 15:28:41 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58373/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:28:41 INFO - PROCESS | 2344 | RSS: Main: 157179904 15:28:41 INFO - PROCESS | 2344 | 15:28:44 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58373/tests/kraken/driver.html?testName=imaging-darkroom) 15:28:45 INFO - PROCESS | 2344 | RSS: Main: 161443840 15:28:45 INFO - PROCESS | 2344 | 15:28:48 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58373/tests/kraken/driver.html?testName=imaging-desaturate) 15:28:49 INFO - PROCESS | 2344 | RSS: Main: 159956992 15:28:49 INFO - PROCESS | 2344 | 15:28:52 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58373/tests/kraken/driver.html?testName=json-parse-financial) 15:28:53 INFO - PROCESS | 2344 | RSS: Main: 159780864 15:28:53 INFO - PROCESS | 2344 | 15:28:54 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58373/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:28:55 INFO - PROCESS | 2344 | RSS: Main: 160038912 15:28:55 INFO - PROCESS | 2344 | 15:28:57 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:28:57 INFO - PROCESS | 2344 | RSS: Main: 159727616 15:28:57 INFO - PROCESS | 2344 | 15:28:59 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:29:00 INFO - PROCESS | 2344 | RSS: Main: 160378880 15:29:00 INFO - PROCESS | 2344 | 15:29:03 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:29:03 INFO - PROCESS | 2344 | RSS: Main: 160153600 15:29:03 INFO - PROCESS | 2344 | 15:29:06 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:29:07 INFO - PROCESS | 2344 | RSS: Main: 160362496 15:29:07 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:58373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58373/tests/kraken/driver.html?testName=ai-astar) 15:29:09 INFO - PROCESS | 2344 | RSS: Main: 162013184 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | __start_tp_report 15:29:09 INFO - PROCESS | 2344 | _x_x_mozilla_page_load 15:29:09 INFO - PROCESS | 2344 | _x_x_mozilla_page_load_details 15:29:09 INFO - PROCESS | 2344 | |i|pagename|runs| 15:29:09 INFO - PROCESS | 2344 | |0;ai-astar;104;114;111;113;112;112;112;113;111;111 15:29:09 INFO - PROCESS | 2344 | |1;audio-beat-detection;152;127;122;164;123;122;124;122;141;121 15:29:09 INFO - PROCESS | 2344 | |2;audio-dft;164;162;163;163;163;169;166;166;164;163 15:29:09 INFO - PROCESS | 2344 | |3;audio-fft;75;76;79;112;76;76;77;77;104;79 15:29:09 INFO - PROCESS | 2344 | |4;audio-oscillator;85;85;106;85;84;84;96;84;84;132 15:29:09 INFO - PROCESS | 2344 | |5;imaging-gaussian-blur;117;119;118;121;119;118;119;118;119;118 15:29:09 INFO - PROCESS | 2344 | |6;imaging-darkroom;149;148;149;150;148;148;149;149;148;147 15:29:09 INFO - PROCESS | 2344 | |7;imaging-desaturate;97;97;97;97;102;97;97;98;96;96 15:29:09 INFO - PROCESS | 2344 | |8;json-parse-financial;57;57;58;58;58;59;57;60;61;58 15:29:09 INFO - PROCESS | 2344 | |9;json-stringify-tinderbox;71;70;72;69;69;69;69;69;70;69 15:29:09 INFO - PROCESS | 2344 | |10;stanford-crypto-aes;83;86;85;83;84;83;84;82;85;90 15:29:09 INFO - PROCESS | 2344 | |11;stanford-crypto-ccm;132;131;127;128;132;128;138;129;167;141 15:29:09 INFO - PROCESS | 2344 | |12;stanford-crypto-pbkdf2;168;184;167;168;169;170;169;167;173;165 15:29:09 INFO - PROCESS | 2344 | |13;stanford-crypto-sha256-iterative;65;65;68;63;67;66;62;63;62;66 15:29:09 INFO - PROCESS | 2344 | __end_tp_report 15:29:09 INFO - PROCESS | 2344 | __start_cc_report 15:29:09 INFO - PROCESS | 2344 | _x_x_mozilla_cycle_collect,735 15:29:09 INFO - PROCESS | 2344 | __end_cc_report 15:29:09 INFO - PROCESS | 2344 | __startTimestamp1483054149359__endTimestamp 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | ------- Summary: start ------- 15:29:09 INFO - PROCESS | 2344 | Number of tests: 14 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:1.05 15:29:09 INFO - PROCESS | 2344 | Values: 104.0 114.0 111.0 113.0 112.0 112.0 112.0 113.0 111.0 111.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:125.50 stddev:15.26 (12.2%) stddev-sans-first:14.33 15:29:09 INFO - PROCESS | 2344 | Values: 152.0 127.0 122.0 164.0 123.0 122.0 124.0 122.0 141.0 121.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.11 (1.3%) stddev-sans-first:2.24 15:29:09 INFO - PROCESS | 2344 | Values: 164.0 162.0 163.0 163.0 163.0 169.0 166.0 166.0 164.0 163.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.50 stddev:13.32 (17.4%) stddev-sans-first:13.80 15:29:09 INFO - PROCESS | 2344 | Values: 75.0 76.0 79.0 112.0 76.0 76.0 77.0 77.0 104.0 79.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:84.50 stddev:15.68 (18.6%) stddev-sans-first:16.39 15:29:09 INFO - PROCESS | 2344 | Values: 85.0 85.0 106.0 85.0 84.0 84.0 96.0 84.0 84.0 132.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#5] imaging-gaussian-blur Cycles:10 Average:118.60 Median:119.00 stddev:1.07 (0.9%) stddev-sans-first:0.97 15:29:09 INFO - PROCESS | 2344 | Values: 117.0 119.0 118.0 121.0 119.0 118.0 119.0 118.0 119.0 118.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 15:29:09 INFO - PROCESS | 2344 | Values: 149.0 148.0 149.0 150.0 148.0 148.0 149.0 149.0 148.0 147.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:97.00 stddev:1.71 (1.8%) stddev-sans-first:1.81 15:29:09 INFO - PROCESS | 2344 | Values: 97.0 97.0 97.0 97.0 102.0 97.0 97.0 98.0 96.0 96.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.33 15:29:09 INFO - PROCESS | 2344 | Values: 57.0 57.0 58.0 58.0 58.0 59.0 57.0 60.0 61.0 58.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:1.06 (1.5%) stddev-sans-first:1.01 15:29:09 INFO - PROCESS | 2344 | Values: 71.0 70.0 72.0 69.0 69.0 69.0 69.0 69.0 70.0 69.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.50 stddev:2.27 (2.7%) stddev-sans-first:2.35 15:29:09 INFO - PROCESS | 2344 | Values: 83.0 86.0 85.0 83.0 84.0 83.0 84.0 82.0 85.0 90.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:132.00 stddev:12.02 (9.1%) stddev-sans-first:12.69 15:29:09 INFO - PROCESS | 2344 | Values: 132.0 131.0 127.0 128.0 132.0 128.0 138.0 129.0 167.0 141.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:169.00 stddev:5.35 (3.2%) stddev-sans-first:5.63 15:29:09 INFO - PROCESS | 2344 | Values: 168.0 184.0 167.0 168.0 169.0 170.0 169.0 167.0 173.0 165.0 15:29:09 INFO - PROCESS | 2344 | 15:29:09 INFO - PROCESS | 2344 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.50 stddev:2.11 (3.2%) stddev-sans-first:2.24 15:29:09 INFO - PROCESS | 2344 | Values: 65.0 65.0 68.0 63.0 67.0 66.0 62.0 63.0 62.0 66.0 15:29:09 INFO - PROCESS | 2344 | -------- Summary: end -------- 15:29:09 INFO - PROCESS | 2344 | 15:29:10 INFO - TEST-INFO | 2344: exit 0 15:29:10 INFO - TEST-OK | kraken | took 61176ms 15:29:10 INFO - SUITE-END | took 1174s 15:29:10 INFO - Completed test suite (00:19:34) 15:29:10 INFO - javascript benchmark 15:29:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7166.812787081778, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3209.5667084943534, 3251.4309995750755, 3256.7883087599303, 3284.3102948380847, 3296.9010929672118, 5281.656692314997, 5535.833924982252, 5558.638688485245, 5565.554755619551, 5567.0, 5311.57507399408, 5389.434109418504, 5434.701091439969, 5457.508824205821, 5498.4501549845, 4851.393575803016, 4869.853904382874, 4908.656394052417, 4980.651354405193, 4998.77505512251, 4756.405449318835, 5000.049990501811, 5062.569681577071, 5080.491950804909, 5084.237364395333, 4812.807487700487, 4949.257611358289, 4971.676841005359, 4982.078315511624, 5051.621128415365, 4833.4441539222735, 4834.226523756217, 4848.5151484851585, 4904.730239836807, 4987.501249875019, 4824.758762061918, 4872.4883887192045, 4879.658423910328, 4908.361912951329, 4908.7300198489065, 5334.4398838122215, 5354.089804733205, 5365.597580181502, 5372.382176049726, 5441.863953401196, 5722.341930677983, 5770.596058275923, 5809.593328467009, 5852.648841069549, 5863.912041319383, 5960.940390596082, 5960.940390596125, 5961.5230781537375, 5963.52291816658, 6011.849703757441, 5472.343318801769, 5528.612997090206, 5531.889362212773, 5537.335519737658, 5537.446255374511, 5871.677057761779, 5921.881562368729, 5924.496417804448, 5955.970220148958, 6104.26748790148, 5649.350324712628, 5676.318841738936, 5710.828675139669, 5741.282339707536, 5757.942420575824, 5756.194132821408, 5769.105788602773, 5783.161441590946, 5811.15738217956, 5828.562857785683, 5661.716914154275, 5722.942770572241, 5845.795397161164, 5873.765049398062, 5887.970560147171, 5919.052951527735, 5928.288605367384, 5936.495397891227, 5938.732757025944, 5984.521238300926, 4423.424954755926, 4445.333200020023, 4446.177457170434, 4450.06548624786, 4484.0583477469445, 3886.2810380079777, 3904.492415985904, 3908.257431088141, 3919.2553414851086, 3944.9408258876138, 5573.359063707644, 5588.776448941997, 5632.436756324335, 5658.462446067617, 5723.828285151452, 5869.295684517885, 5893.057110862242, 5910.7931222407005, 5959.553033522503, 5965.433283838029, 5375.381831089475, 5401.594880383908, 5564.081926482166, 5595.468430499178, 5600.803971861047, 4893.290472881412, 4912.631552633496, 4924.43369012561, 4925.310456536088, 4956.4547899731, 6163.322034576193, 6194.5044396448175, 6227.564070515111, 6227.657478838617, 6257.68711564429, 5730.799422020209, 5762.855928601818, 5960.5231581474245, 5966.940330596812, 5971.0, 6068.393160683896, 6085.57400981927, 6116.853194528166, 6118.112873633209, 6151.169592105188, 5668.37647858727, 5715.514181294637, 5724.713764311685, 5748.45389687979, 5786.797462088806, 5770.105633626795, 5817.47642712141, 5827.3007239131575, 5870.207521984477, 5882.441168089024, 5656.236408084856, 5736.0, 5758.568107391879, 5794.159846822103, 5866.560007999503, 5819.185314055951, 5830.096335068071, 5831.35855055935, 5834.15404766298, 5851.765929362778, 5635.605507614591, 5640.943590564042, 5834.82495525118, 5839.970800146142, 5843.766249349978, 5749.338826035059, 5792.420757924174, 5853.64878107315, 5865.94134058654, 5928.79249226275, 5668.178114173339, 5707.6290041147195, 5713.057345538106, 5714.228579141763, 5715.057015592381], "value": 5401.325895231396, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.9586780991425, 558.6424688199551, 566.3289002532, 573.088788825767, 573.3893403525246, 40091.198176036465, 41868.23185913267, 42197.78901105494, 45169.0, 45236.0, 43364.349534756926, 43480.782596087054, 43490.0, 43571.0, 43596.564034359646, 8905.821883562341, 9065.818683626356, 9070.27437804974, 9105.95447022764, 10041.246906482043, 10067.89932100677, 10280.12618927392, 10313.793724125513, 10366.170706343511, 10376.688699339033, 10296.33073850201, 10485.213608979318, 10486.737831554196, 10487.842682359733, 10497.37015779052, 10302.278840481169, 10390.896091039069, 10411.583536658525, 10415.791684166312, 10429.217808664305, 10048.79902401948, 10096.848547271797, 10175.13511351536, 10219.284650074498, 10285.537150828231, 37229.81385093084, 37315.44026839599, 37437.43843842344, 37567.62432375669, 37814.0, 4033.1328764315813, 4036.091879327157, 4108.137291168858, 4177.603127702894, 4187.497500299984, 28513.287167820763, 28659.57010644842, 28780.136595901946, 29003.274918127216, 29571.0, 4252.192083504125, 4316.266234740102, 4348.7390756554705, 4363.799283154147, 4390.8902277443, 311728.0, 312954.0, 313073.0, 313383.0, 316293.0, 50935.63304586927, 50983.09761986898, 51217.23174152391, 51358.0, 51393.743031285354, 53381.733091334296, 53542.732286338316, 53761.731191343795, 53856.46143538593, 53870.191947120824, 15218.467353642569, 15250.161241131622, 15381.384744610314, 15396.923015384851, 15432.614184645248, 10833.0, 11010.284331518425, 11698.181127321175, 11735.23720958135, 11766.235194712317, 16682.58293542671, 16710.498685039467, 16715.247813848408, 16748.399094194807, 16786.160691965353, 6511.674416279167, 6577.539572229898, 6595.538312318187, 6819.863602727918, 6882.7591034313555, 906.1527471813876, 916.4501299220415, 919.2921450483224, 923.2521657457481, 963.6241865672393, 29028.854855725585, 29376.0, 29423.264418389284, 30776.53835192474, 30837.383252335276, 1050.6900464363032, 1068.6847380022912, 1072.2279958429917, 1097.2100087936674, 1188.9940550297192, 15455.690886182214, 16173.514794556182, 16268.349266029227, 16319.592010199602, 16469.752953705705, 455123.0, 456638.0, 456814.0, 457221.0, 458252.0, 56955.43044569501, 57248.42751572514, 57822.7108864453, 57877.71061144752, 58211.70894145587, 1333.5199328241824, 1384.3977869626744, 1385.4942945824903, 1390.1381143690976, 1390.2631605249235, 28396.43207135887, 29082.27294317659, 29944.700552995062, 29958.25104372408, 30314.242143946576, 48039.51960480351, 48978.26532602085, 49357.506424935294, 49560.0, 49601.75199123981, 381884.0, 381983.0, 382022.0, 383939.0, 384825.0, 25053.74946250514, 25421.0, 25452.490950180523, 26338.707661542307, 27281.0, 41294.1741165169, 41483.37774933318, 41555.0, 41722.58277417187, 42333.0, 33459.665403346626, 35144.64855351516, 35419.468707969914, 35558.46662300016, 35625.46561801523, 34796.478052829734, 34892.65107348894, 34956.47565286472, 35172.29655406929, 35577.288454230256, 34874.30251394907, 35339.0, 35358.292834143685, 35438.82280588581, 36031.27937441289, 30182.0, 30891.69108308888, 32221.838890805397, 32413.0, 32425.35149297048, 24276.635850461906, 24596.754032459445, 24777.62833557462, 24800.0, 24994.250172494852], "value": 19096.79381486557, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.28749245135174, 388.79588216186517, 402.47678018575846, 412.3217307529115, 424.7387856468271, 14201.0, 17205.569860753487, 18250.452486425394, 18685.159167837464, 18704.0, 18278.0, 18721.531961700963, 18848.717269240937, 19037.904810475928, 19192.328268510606, 5170.844874653766, 5341.946580534203, 5405.297311349539, 5418.160185171305, 5439.048166570854, 5326.6005049621435, 5375.731213439312, 5389.245505629215, 5403.054465468547, 5710.885782284352, 5950.0, 6074.210352654149, 6090.208272924514, 6096.69516524172, 6116.908246376286, 6067.787627433041, 6070.059140833156, 6141.232345956733, 6511.804645860631, 6548.508861835334, 5843.474087332161, 5852.67810270435, 5857.589968702193, 5865.266841644794, 5865.677387743673, 13993.790093148611, 14075.788863167061, 14196.290185490685, 14207.715845683131, 17069.91465042667, 2197.648376259807, 2200.9655461932866, 2201.2295696506253, 2214.8892555372167, 2239.596872562938, 10199.796004079952, 10411.167106631452, 10445.216608754374, 11454.427278636118, 14983.400663973536, 1902.3436914264557, 1982.3854605072474, 1992.9202831886853, 2000.089959068635, 2003.889786061766, 78487.21512784914, 78533.0, 79115.41769164584, 79956.0, 80323.0, 10902.400367979679, 10974.341539507655, 11078.335299882034, 11191.496382662799, 11323.0, 15345.232738363036, 15388.153651549048, 15439.764818814469, 15575.454859079879, 15598.298076586354, 7491.250874912575, 7593.31660150578, 7859.214078592095, 7954.284114429729, 8001.199880011952, 7143.1428228612895, 7175.103112110986, 7270.345668889825, 7445.329920307198, 7448.73929412468, 4855.587275081587, 4855.902881942341, 4873.95126048735, 4884.291777692215, 4921.532454416824, 2037.8064083912004, 2059.9588008240053, 2072.658011428128, 2087.00514761318, 2097.0, 1009.1724785675822, 1028.5937054863286, 1048.7325731938306, 1070.1385384765338, 1094.1137678480457, 18119.637607248045, 18368.908155459136, 18582.25670973146, 18907.621847562972, 18993.62012759737, 1016.4917541229386, 1030.3766221436074, 1040.7606250562412, 1043.7286305560608, 1064.7870425914693, 10093.798124037477, 10592.89407105919, 10603.09873660732, 10703.892961070289, 10811.081058109992, 324161.0, 324934.0, 326866.0, 407511.0, 407726.8068298316, 92151.53924230336, 92867.53566232261, 94320.0, 94656.52671736597, 94791.0, 1004.4324956399611, 1016.4866742295094, 1058.3861360411097, 1061.570064124031, 1069.2408390043156, 17047.40334088301, 17136.40022599203, 17576.91211543934, 17637.26622373534, 17667.55831104233, 40519.3922091163, 41270.0, 41457.58542414537, 41533.58466415418, 41695.374569380874, 52288.65938172933, 55397.0, 56236.0, 57231.0, 58265.41734582769, 8565.143485651384, 8647.87028194565, 8867.201951824367, 8951.597178127131, 9050.592723327334, 12508.249505029728, 12874.227546347249, 13130.803037954247, 13199.472021119047, 13393.732125357634, 10780.784384312426, 11178.217524773188, 11432.371219583003, 11589.594364197214, 12278.386080696107, 11488.310701357945, 11602.825957610812, 11638.418079095842, 11654.766904662029, 11668.649940501798, 11685.415729213335, 11865.466054027416, 11881.405929703653, 11945.0, 12126.939365303117, 7491.1385190701585, 7676.961615191888, 8762.386632935633, 8871.334649901155, 8877.0, 11283.774324513628, 11546.82679759821, 11739.823902641296, 12011.63965081049, 12230.388480576114], "value": 9042.402018249353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.8280214973129, 1410.9365078571466, 1413.1379858286443, 1417.3551034279403, 1417.4613646814212, 8118.553479558622, 8491.830163396728, 8590.95704521478, 8640.697575584856, 8710.60802263898, 8619.353548483858, 8801.55992200389, 8847.071057538962, 8869.0, 8893.466392016468, 2353.329301149173, 2367.739548649647, 2369.6208606622945, 2382.094803974487, 2389.8685572293602, 2102.926397576085, 2168.5012447137087, 2287.0737351372723, 2379.583572874749, 2397.976020239793, 2377.6909001829827, 2413.5293617744546, 2417.093589903786, 2423.994042472372, 2424.3939015246187, 2249.9437514062115, 2252.9523771446275, 2266.5013696986634, 2296.4488522754586, 2301.9884900575476, 2341.9695334052963, 2344.284993077111, 2344.48421347305, 2346.9295921122393, 2386.570417324881, 8651.826963460695, 8692.913070869274, 8748.25639820616, 8765.561721913879, 8800.515971621558, 5215.322008138955, 5333.679979201222, 5338.706371149585, 5362.1420572708175, 5372.892542149135, 3365.9831700841582, 3557.7865328080397, 3584.1039740064984, 3635.3456377851976, 3663.9084022899374, 1399.3842709207916, 1449.6013596261007, 1463.290304202461, 1474.6460849396067, 1514.3336931749996, 11252.099832013419, 11279.0, 11296.378699171542, 11327.433628318551, 11341.886581134166, 4321.3301938199, 4462.174497717932, 4532.4334458192725, 4571.634269258451, 4638.0, 9270.119338662951, 9309.069093090637, 9405.247580193705, 9417.482038487811, 9429.952850235704, 5190.325257716473, 5286.788528458818, 5307.336582927134, 5317.73411329432, 5323.201519772065, 4891.779869905863, 4903.387076615423, 4918.5573298403315, 4922.606191504671, 4926.285688575136, 3643.0892276930767, 3659.9817000914827, 3697.6302369762807, 3719.9442008369897, 3879.7478163919254, 1097.4951522299673, 1118.2284223885492, 1148.7702459508132, 1327.595083499542, 1361.877431031212, 933.8459154239611, 957.2437774158337, 957.9329446938648, 960.5533426956499, 973.4986481961795, 5320.627556071116, 5777.075667893117, 5787.392323806024, 5804.593678442553, 5911.970440147772, 913.3150137396952, 962.2398305338844, 968.2496065549255, 1005.0, 1014.1481155829139, 3929.724919255682, 3992.2215168041953, 4129.442525259112, 4148.336266197454, 4178.415021896937, 10195.541200645988, 10466.63366782159, 10544.051035406697, 10545.103666188306, 10577.0, 10167.23745719074, 10323.690289291333, 10346.965303469593, 10357.274990750726, 10361.792764144826, 7890.0, 8094.878576821352, 8113.391495637851, 8135.227153420416, 8164.673413063411, 5827.970860145672, 5847.766089356378, 5868.67722275279, 5901.793437229848, 5958.940410595838, 9525.714228573153, 9580.377275477073, 9602.471864047544, 9611.711648650551, 9638.277129215197, 9070.81858362816, 9230.861537077084, 9385.20225780789, 9568.043195680377, 9630.951845240728, 7516.060492438445, 7698.0762308523335, 7712.2647404333575, 7712.305892469481, 7723.729669461542, 7837.647305871364, 7839.725609603636, 7908.960455197687, 7922.524648521107, 7966.3626909848235, 6478.509942713296, 7081.504294699527, 7095.8935615964765, 7213.6032518212005, 7499.0, 7343.742968996059, 7364.484486086137, 7399.408047356091, 7442.4418168636485, 7540.622968851645, 6474.611523308617, 6534.542582019213, 6540.869182616226, 6569.770058047945, 6625.370589793962, 5122.154844450624, 5143.177091665316, 5150.819721309736, 5156.510131537648, 5237.0835103857435, 5031.320771695774, 5116.386033675983, 5168.8449346519665, 5206.062908676475, 5257.2639830423], "value": 4564.713367551257, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.1046317312249, 393.8503368719886, 397.8289335585697, 413.23138961531566, 417.5782459715686, 5111.309973153632, 5243.239730239123, 5297.814576489824, 5318.813841515548, 5369.603902985223, 5162.92255616166, 5243.344581927249, 5249.711265880384, 5252.842414727555, 5281.762320695569, 4226.640735537483, 4227.239096962546, 4239.872803815891, 4243.809028593705, 4254.6170844623975, 4093.856715014975, 4244.363345498169, 4255.6380459437005, 4277.0, 4311.0, 4182.22628813669, 4194.035371864473, 4195.643370313527, 4210.557891421403, 4228.76741779202, 4158.2307273154565, 4190.832366705344, 4208.158368326316, 4211.410402543646, 4223.282042052859, 3823.7323387362894, 3824.3307421201457, 3844.5770965193806, 3845.3847384418636, 3845.557760857509, 5082.263071854597, 5123.743812809382, 5142.1772516397195, 5180.4042535108565, 5197.674592978766, 4813.735244561548, 4876.414830220361, 4876.731779752147, 4876.902461950776, 4894.388201474816, 5476.644018138808, 5543.362513310981, 5563.638363506399, 5576.665400076008, 5590.468905453975, 4182.728122672017, 4185.844290190555, 4193.7903104844945, 4214.220369231672, 4221.430106935555, 5738.167965645, 5749.913751293734, 5760.135979603051, 5781.075027995501, 5819.53443724501, 4188.141431006637, 4286.592773686495, 4316.611504964569, 4324.243257429937, 4336.739795612273, 4299.54854740254, 4335.28467802809, 4344.6089851913475, 4393.1872603568445, 4398.296272596369, 4835.153848076572, 4868.294097355863, 4887.29134275528, 4898.069366820293, 4915.557599816034, 5515.420880807537, 5596.2152359731845, 5610.49505544503, 5618.887622247532, 5626.0, 1456.3519233941006, 1475.970480590382, 1490.9254537273093, 1497.071815474413, 1500.0, 1023.3399457349967, 1076.9445942975929, 1083.4236186348844, 1085.46812062089, 1094.6661268313242, 5132.615053870974, 5134.05020071288, 5154.690718556899, 5180.611454140954, 5182.97408512955, 5389.730513474311, 5456.972715136399, 5524.309461317335, 5534.754680197004, 5543.916841247385, 5093.439721630692, 5193.533071790869, 5207.244949482304, 5238.7904483820985, 5255.5795536357, 2893.7540309073543, 2898.4058268054996, 2907.7383035526905, 2931.589577459157, 2937.8237305761722, 5647.322321321386, 5780.768769249183, 5781.71091445426, 5793.710314484259, 5806.21616081832, 5441.6190866639745, 5588.0779671354585, 5620.859478512988, 5634.774609015675, 5637.436256374412, 5821.708914554255, 5821.94178058214, 5822.0, 5845.7369418376275, 5876.882462350814, 5331.466853314638, 5409.945900541102, 5458.972705136449, 5469.945300546943, 5698.715064246689, 5635.633683810539, 5661.348944871228, 5695.288088988877, 5705.942940570707, 5717.771289148387, 5948.196993405835, 5979.847254988288, 6027.065804800263, 6069.666168360783, 6101.816945491642, 4032.1371826003865, 4136.242097108657, 4167.0, 4179.05376709413, 4180.456540649696, 5751.396103409082, 5779.768809247583, 5810.854728631818, 5830.2129212555765, 5836.503897168618, 5499.945000549943, 5686.459786320293, 5709.4576015280145, 5715.17130016137, 5719.3136823581435, 5605.5795815313195, 5613.698383662679, 5626.831195064155, 5646.463585959327, 5651.321841379061, 5616.578756593353, 5625.268715067015, 5645.491905728504, 5645.927273817962, 5672.574556908331, 5306.734663266899, 5542.501174894118, 5618.831435056954, 5670.971645141913, 5672.914906276492, 5444.537214336826, 5482.314710661168, 5494.011078005838, 5496.835094947158, 5507.779688812403], "value": 4316.883565440577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.340207215578, 769.4806005945985, 787.5747096567853, 809.3727361294996, 823.4112609484217, 14671.926640366797, 15064.0, 15069.095854248753, 15638.765418518717, 16131.354745810168, 14783.0, 14907.701845963102, 15170.54488365348, 15188.31652575634, 15346.079235245865, 801.8636831738589, 815.7960509872547, 836.9832603347929, 843.464400105931, 845.0619812008665, 835.7242110103651, 841.3899922556159, 842.3471809347743, 843.0178841649471, 843.1947490146925, 867.5878957495171, 868.5570359116833, 868.7828042989253, 871.5293741379647, 871.7428358634187, 871.372611719561, 873.5413907698446, 876.176394189463, 876.9123087691244, 877.4209022045451, 642.958207716497, 644.5455953552735, 645.4674893213117, 645.7384759172543, 646.2116218213772, 16255.349786008665, 16348.427805026886, 16395.672086558323, 16488.0, 16677.249523771337, 14934.850651493563, 15086.321115549827, 15146.848531514654, 15338.693226135527, 15469.613259668598, 12297.508099676003, 12585.119041667087, 12598.370081495888, 12655.43050562727, 12703.428345724373, 3786.318462676717, 3842.6733727632904, 3872.8257228424786, 3882.864099756495, 3903.1413089120347, 87200.5639971796, 88192.0, 90231.09768902358, 90257.54871225667, 90929.09070909339, 10891.03961286976, 10936.781264374828, 11007.779844403067, 11046.834297485544, 11097.1122310215, 21983.780162198495, 22011.449713756963, 22120.336389908327, 22246.88876555607, 22258.22096226624, 7979.241972012649, 8219.136990616018, 8294.419390642597, 8326.50040997542, 8384.413091083565, 4530.29780384041, 4582.312653102062, 4611.953880461152, 4613.63090952723, 4764.714117152982, 3590.533230680047, 3642.927141457209, 3673.228621989412, 3673.6142705016123, 3704.1665625234104, 626.7931582577829, 627.1783963008473, 640.38203133975, 645.2869579115104, 647.6049609738056, 732.0484461329359, 756.1493320014984, 761.4137114421865, 778.8559116563454, 784.1310123319647, 9739.41563506192, 9771.364861283993, 10389.220808439397, 10421.739456513496, 10529.631462898762, 611.126089691745, 627.1188979483742, 628.7045088808253, 630.3759278314436, 633.8795628830508, 4458.398116254264, 4622.560856718606, 4687.0, 4717.905641887143, 4733.881652958635, 68534.65732671304, 76899.0, 78131.21868781239, 78427.0, 81955.5902220497, 70831.64584177149, 72336.63831680808, 72636.91044634335, 72753.0, 74625.25374746183, 49534.532582718864, 60829.0, 61767.382326176164, 61919.38080619226, 62927.37072629215, 8875.289976801969, 9090.681826136053, 9121.133492318218, 9205.539723013957, 9229.630814767334, 43657.34513982356, 44265.557344427434, 44401.11197776091, 45302.773486132355, 46784.0, 47007.29489057735, 47662.28506572473, 48527.51472485371, 52672.20991685204, 55029.174562382395, 13075.280859552817, 13184.143030702973, 13493.797593036308, 13595.524156654865, 13964.4414223434, 14605.488807892098, 14816.481423150139, 15694.2937567812, 16143.434979775651, 16184.757228641345, 13829.0, 14433.639159021108, 14720.484783032542, 14729.0, 14905.62735931567, 10877.67366978992, 13503.0, 13658.248796316298, 13842.930785346009, 13937.651558711113, 13052.34738263064, 13750.65623359424, 13944.16335019902, 14066.648333791327, 14265.928670356998, 11700.531978720755, 11718.355490448108, 12251.387430628254, 12352.444140013542, 12580.119391642496, 11190.84204820659, 11251.15616328795, 11431.542738290374, 11594.884051159717, 11607.535698572301], "value": 7372.005099551414, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 111.0, 113.0, 112.0, 112.0, 112.0, 113.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 122.0, 164.0, 123.0, 122.0, 124.0, 122.0, 141.0, 121.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 163.0, 163.0, 169.0, 166.0, 166.0, 164.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 79.0, 112.0, 76.0, 76.0, 77.0, 77.0, 104.0, 79.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 106.0, 85.0, 84.0, 84.0, 96.0, 84.0, 84.0, 132.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 118.0, 121.0, 119.0, 118.0, 119.0, 118.0, 119.0, 118.0], "value": 118.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 150.0, 148.0, 148.0, 149.0, 149.0, 148.0, 147.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 102.0, 97.0, 97.0, 98.0, 96.0, 96.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 58.0, 58.0, 59.0, 57.0, 60.0, 61.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 72.0, 69.0, 69.0, 69.0, 69.0, 69.0, 70.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 83.0, 84.0, 83.0, 84.0, 82.0, 85.0, 90.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 131.0, 127.0, 128.0, 132.0, 128.0, 138.0, 129.0, 167.0, 141.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 184.0, 167.0, 168.0, 169.0, 170.0, 169.0, 167.0, 173.0, 165.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 63.0, 67.0, 66.0, 62.0, 63.0, 62.0, 66.0], "value": 64.7, "unit": "score"}]}]} 15:29:10 INFO - Return code: 0 15:29:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:29:10 ERROR - # TBPL SUCCESS # 15:29:10 INFO - Running post-action listener: _resource_record_post_action 15:29:10 INFO - [mozharness: 2016-12-29 23:29:10.652732Z] Finished run-tests step (success) 15:29:10 INFO - Running post-run listener: _resource_record_post_run 15:29:10 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402964480; Read time: 8; Write time: 308380 15:29:10 INFO - TinderboxPrint: CPU usage
13.4% 15:29:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:29:10 INFO - TinderboxPrint: I/O write bytes / time
402,964,480 / 308,380 15:29:10 INFO - TinderboxPrint: CPU idle
8,231.9 (86.6%) 15:29:10 INFO - TinderboxPrint: CPU user
1,218.2 (12.8%) 15:29:10 INFO - TinderboxPrint: Swap in / out
0 / 0 15:29:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105414656; Read time: 0; Write time: 95028 15:29:10 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 297549824; Read time: 0; Write time: 213352 15:29:11 INFO - Running post-run listener: _upload_blobber_files 15:29:11 INFO - Blob upload gear active. 15:29:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:29:11 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 15:29:11 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:29:11 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:29:11 INFO - (blobuploader) - INFO - Open directory for files ... 15:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:12 INFO - (blobuploader) - INFO - Done attempting. 15:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:13 INFO - (blobuploader) - INFO - Done attempting. 15:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:17 INFO - (blobuploader) - INFO - Done attempting. 15:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:29:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:18 INFO - (blobuploader) - INFO - Done attempting. 15:29:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:29:18 INFO - Return code: 0 15:29:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:29:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:29:18 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c18cb49a195c1993870974fd136cd13a672d50fb32eafd424192131424b3d1ed31f428b85649f1a91af86037ab8072e7183c281aa5e8651c537b903c8a500627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c754b6910cf3514f3d5a9970e9f3a17e57658f815f97073e19a23a059a3eca5f66d8d49701e31498d30fae35d93149866b422f10b1513d798a1edbb95cd41cc6", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98482338f15f53c1d42963c7f6c5ff2610bbf6ad7e9ddaeab6400323320d18feb266602f5fe929ad134866287c4d02a682bc30a49288d66d501867070415a5df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6049af60567fe5abd0557cad41e9dc2a5d5e88bbe708c30b48d2717e74b5b0287dacb9f1bdf5389b5557f3e3c4038ae399b108a20d51fbee5b3ca1a681417d50"} 15:29:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:29:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:29:18 INFO - Contents: 15:29:18 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c18cb49a195c1993870974fd136cd13a672d50fb32eafd424192131424b3d1ed31f428b85649f1a91af86037ab8072e7183c281aa5e8651c537b903c8a500627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c754b6910cf3514f3d5a9970e9f3a17e57658f815f97073e19a23a059a3eca5f66d8d49701e31498d30fae35d93149866b422f10b1513d798a1edbb95cd41cc6", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98482338f15f53c1d42963c7f6c5ff2610bbf6ad7e9ddaeab6400323320d18feb266602f5fe929ad134866287c4d02a682bc30a49288d66d501867070415a5df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6049af60567fe5abd0557cad41e9dc2a5d5e88bbe708c30b48d2717e74b5b0287dacb9f1bdf5389b5557f3e3c4038ae399b108a20d51fbee5b3ca1a681417d50"} 15:29:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:29:18 INFO - Copying logs to upload dir... 15:29:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:29:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.600318 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-29 15:29:17.119083) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 15:29:17.122786) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c18cb49a195c1993870974fd136cd13a672d50fb32eafd424192131424b3d1ed31f428b85649f1a91af86037ab8072e7183c281aa5e8651c537b903c8a500627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c754b6910cf3514f3d5a9970e9f3a17e57658f815f97073e19a23a059a3eca5f66d8d49701e31498d30fae35d93149866b422f10b1513d798a1edbb95cd41cc6", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98482338f15f53c1d42963c7f6c5ff2610bbf6ad7e9ddaeab6400323320d18feb266602f5fe929ad134866287c4d02a682bc30a49288d66d501867070415a5df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6049af60567fe5abd0557cad41e9dc2a5d5e88bbe708c30b48d2717e74b5b0287dacb9f1bdf5389b5557f3e3c4038ae399b108a20d51fbee5b3ca1a681417d50"} build_url:https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/Fme11XnIRC6mbq_6BSA22w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c18cb49a195c1993870974fd136cd13a672d50fb32eafd424192131424b3d1ed31f428b85649f1a91af86037ab8072e7183c281aa5e8651c537b903c8a500627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c754b6910cf3514f3d5a9970e9f3a17e57658f815f97073e19a23a059a3eca5f66d8d49701e31498d30fae35d93149866b422f10b1513d798a1edbb95cd41cc6", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98482338f15f53c1d42963c7f6c5ff2610bbf6ad7e9ddaeab6400323320d18feb266602f5fe929ad134866287c4d02a682bc30a49288d66d501867070415a5df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6049af60567fe5abd0557cad41e9dc2a5d5e88bbe708c30b48d2717e74b5b0287dacb9f1bdf5389b5557f3e3c4038ae399b108a20d51fbee5b3ca1a681417d50"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 15:29:17.151980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:29:17.152333) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483052349.670624-276801568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:29:17.235435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 15:29:17.235778) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 15:29:17.236098) ========= ========= Total master_lag: 0.17 =========