builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1481767793.62 results: success (0) buildid: 20161214163014 builduid: a4ede97c04ac44a88e0da9a4b7233403 revision: b1ab720c6d3e412ede797b08dfe63dca170f6ee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.622155) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.622648) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.622937) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.637935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.638329) ========= 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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-14 18:09:53-- 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% 33.0M=0s 2016-12-14 18:09:54 (33.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135006 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.786963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.787290) ========= 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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.836541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:53.836902) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b1ab720c6d3e412ede797b08dfe63dca170f6ee0 --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 b1ab720c6d3e412ede797b08dfe63dca170f6ee0 --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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-14 18:09:54,173 truncating revision to first 12 chars 2016-12-14 18:09:54,173 Setting DEBUG logging. 2016-12-14 18:09:54,173 attempt 1/10 2016-12-14 18:09:54,173 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b1ab720c6d3e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 18:09:54,531 unpacking tar archive at: mozilla-central-b1ab720c6d3e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.690283 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:54.541885) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:54.542191) ========= script_repo_revision: b1ab720c6d3e412ede797b08dfe63dca170f6ee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:54.542608) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:54.542882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 18:09:54.551168) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-14 18:09:54.551563) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:09:54 INFO - MultiFileLogger online at 20161214 18:09:54 in /builds/slave/test 18:09:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:09:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:09:54 INFO - {'append_to_log': False, 18:09:54 INFO - 'base_work_dir': '/builds/slave/test', 18:09:54 INFO - 'blob_upload_branch': 'Firefox', 18:09:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:09:54 INFO - 'branch': 'Firefox', 18:09:54 INFO - 'buildbot_json_path': 'buildprops.json', 18:09:54 INFO - 'config_files': ('talos/linux_config.py',), 18:09:54 INFO - 'default_actions': ('clobber', 18:09:54 INFO - 'read-buildbot-config', 18:09:54 INFO - 'download-and-extract', 18:09:54 INFO - 'populate-webroot', 18:09:54 INFO - 'create-virtualenv', 18:09:54 INFO - 'install', 18:09:54 INFO - 'run-tests'), 18:09:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:09:54 INFO - 'download_minidump_stackwalk': True, 18:09:54 INFO - 'download_symbols': 'ondemand', 18:09:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:09:54 INFO - 'tooltool.py': '/tools/tooltool.py', 18:09:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:09:54 INFO - '/tools/misc-python/virtualenv.py')}, 18:09:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:09:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:09:54 INFO - 'installer_path': 'installer.exe', 18:09:54 INFO - 'log_level': 'info', 18:09:54 INFO - 'log_name': 'talos', 18:09:54 INFO - 'log_to_console': True, 18:09:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:09:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:09:54 INFO - 'opt_config_files': (), 18:09:54 INFO - 'pip_index': False, 18:09:54 INFO - 'sps_profile': False, 18:09:54 INFO - 'sps_profile_interval': 0, 18:09:54 INFO - 'suite': 'dromaeojs', 18:09:54 INFO - 'system_bits': '32', 18:09:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:09:54 INFO - 'title': 'talos-linux64-ix-054', 18:09:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:09:54 INFO - 'use_talos_json': True, 18:09:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:09:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:09:54 INFO - 'work_dir': 'build'} 18:09:54 INFO - [mozharness: 2016-12-15 02:09:54.982203Z] Running clobber step. 18:09:54 INFO - Running pre-action listener: _resource_record_pre_action 18:09:54 INFO - Running main action method: clobber 18:09:54 INFO - rmtree: /builds/slave/test/build 18:09:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:09:55 INFO - Running post-action listener: _resource_record_post_action 18:09:55 INFO - [mozharness: 2016-12-15 02:09:55.444741Z] Finished clobber step (success) 18:09:55 INFO - [mozharness: 2016-12-15 02:09:55.444840Z] Running read-buildbot-config step. 18:09:55 INFO - Running pre-action listener: _resource_record_pre_action 18:09:55 INFO - Running main action method: read_buildbot_config 18:09:55 INFO - Using buildbot properties: 18:09:55 INFO - { 18:09:55 INFO - "project": "", 18:09:55 INFO - "product": "firefox", 18:09:55 INFO - "script_repo_revision": "production", 18:09:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:09:55 INFO - "repository": "", 18:09:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:09:55 INFO - "buildid": "20161214163014", 18:09:55 INFO - "pgo_build": "True", 18:09:55 INFO - "basedir": "/builds/slave/test", 18:09:55 INFO - "buildnumber": 111, 18:09:55 INFO - "slavename": "talos-linux64-ix-054", 18:09:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:09:55 INFO - "platform": "ubuntu64_hw", 18:09:55 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:09:55 INFO - "branch": "mozilla-central", 18:09:55 INFO - "repo_path": "mozilla-central", 18:09:55 INFO - "revision": "b1ab720c6d3e412ede797b08dfe63dca170f6ee0", 18:09:55 INFO - "stage_platform": "linux64-pgo", 18:09:55 INFO - "builduid": "a4ede97c04ac44a88e0da9a4b7233403", 18:09:55 INFO - "slavebuilddir": "test" 18:09:55 INFO - } 18:09:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:09:55 INFO - Running post-action listener: _resource_record_post_action 18:09:55 INFO - [mozharness: 2016-12-15 02:09:55.447307Z] Finished read-buildbot-config step (success) 18:09:55 INFO - [mozharness: 2016-12-15 02:09:55.447395Z] Running download-and-extract step. 18:09:55 INFO - Running pre-action listener: _resource_record_pre_action 18:09:55 INFO - Running main action method: download_and_extract 18:09:55 INFO - mkdir: /builds/slave/test/build/tests 18:09:55 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')]} 18:09:55 INFO - trying https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:09:55 INFO - Downloading https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/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 18:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/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 18:09:56 INFO - Downloaded 1361 bytes. 18:09:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:09:56 INFO - Using the following test package requirements: 18:09:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:09:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:09:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:09:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'jsshell-linux-x86_64.zip'], 18:09:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:09:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:09:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:09:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:09:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:09:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:09:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:09:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:09:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:09:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:09:57 INFO - Expected file size: 21774438 18:09:57 INFO - Obtained file size: 21774438 18:10:01 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 18:10:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:10:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:10:01 INFO - Fetch https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:10:03 INFO - Expected file size: 21774438 18:10:03 INFO - Obtained file size: 21774438 18:10:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:10:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:10:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:10:09 INFO - Expected file size: 14409734 18:10:09 INFO - Obtained file size: 14409734 18:10: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')]} 18:10:09 INFO - trying https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:10:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/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 18:10:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/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 18:10:12 INFO - Downloaded 59904230 bytes. 18:10:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:10:12 INFO - mkdir: /builds/slave/test/properties 18:10:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:10:12 INFO - Writing to file /builds/slave/test/properties/build_url 18:10:12 INFO - Contents: 18:10:12 INFO - build_url:https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:10:13 INFO - Running post-action listener: _resource_record_post_action 18:10:13 INFO - Running post-action listener: set_extra_try_arguments 18:10:13 INFO - [mozharness: 2016-12-15 02:10:13.197262Z] Finished download-and-extract step (success) 18:10:13 INFO - [mozharness: 2016-12-15 02:10:13.197488Z] Running populate-webroot step. 18:10:13 INFO - Running pre-action listener: _resource_record_pre_action 18:10:13 INFO - Running main action method: populate_webroot 18:10:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:10:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:10:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:10:13 INFO - '--tppagecycles', 18:10:13 INFO - '7'], 18:10:13 INFO - 'tests': ['tsvgm']}}, 18:10:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:10:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:10:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:10:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:10:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['damp', 'tps']}, 18:10:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'tests': ['damp', 'tps']}, 18:10:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['dromaeo_dom']}, 18:10:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:10:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['basic_compositor_video']}, 18:10:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:10:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'other-e10s': {'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['a11yr', 18:10:13 INFO - 'ts_paint', 18:10:13 INFO - 'tpaint', 18:10:13 INFO - 'sessionrestore', 18:10:13 INFO - 'sessionrestore_no_auto_restore', 18:10:13 INFO - 'tabpaint']}, 18:10:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['tsvgx', 18:10:13 INFO - 'tsvgr_opacity', 18:10:13 INFO - 'tart', 18:10:13 INFO - 'tscrollx', 18:10:13 INFO - 'cart', 18:10:13 INFO - 'tsvg_static']}, 18:10:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:10:13 INFO - 'tsvgr_opacity', 18:10:13 INFO - 'tart', 18:10:13 INFO - 'tscrollx', 18:10:13 INFO - 'cart', 18:10:13 INFO - 'tsvg_static']}, 18:10:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'talos_options': ['--disable-e10s'], 18:10:13 INFO - 'tests': ['tp5o']}, 18:10:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:10:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'talos_options': ['--disable-e10s', 18:10:13 INFO - '--xperf_path', 18:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:13 INFO - 'tests': ['tp5n']}, 18:10:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:10:13 INFO - 'talos_options': ['--xperf_path', 18:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:13 INFO - 'tests': ['tp5n']}}, 18:10:13 INFO - 'talos.zip': {'path': '', 18:10:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:10:13 INFO - Running post-action listener: _resource_record_post_action 18:10:13 INFO - [mozharness: 2016-12-15 02:10:13.215646Z] Finished populate-webroot step (success) 18:10:13 INFO - [mozharness: 2016-12-15 02:10:13.215733Z] Running create-virtualenv step. 18:10:13 INFO - Running pre-action listener: _resource_record_pre_action 18:10:13 INFO - Running main action method: create_virtualenv 18:10:13 INFO - Creating virtualenv /builds/slave/test/build/venv 18:10:13 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 18:10:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:10:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:10:13 INFO - Using real prefix '/usr' 18:10:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:10:13 INFO - Installing distribute.............................................................................................................................................................................................done. 18:10:15 INFO - Installing pip.................done. 18:10:15 INFO - Return code: 0 18:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:10:16 INFO - Reading from file tmpfile_stdout 18:10:16 INFO - Output received: 18:10:16 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) 18:10:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:10:16 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')]} 18:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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')]} 18:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:16 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 18:10:16 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 18:10:16 INFO - Using env: {'DISPLAY': ':0', 18:10:16 INFO - 'HOME': '/home/cltbld', 18:10:16 INFO - 'LANG': 'en_US.UTF-8', 18:10:16 INFO - 'LANGUAGE': 'en_US:en', 18:10:16 INFO - 'LOGNAME': 'cltbld', 18:10:16 INFO - 'MAIL': '/var/mail/cltbld', 18:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:16 INFO - 'MOZ_NO_REMOTE': '1', 18:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:16 INFO - 'NO_EM_RESTART': '1', 18:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:16 INFO - 'PWD': '/builds/slave/test', 18:10:16 INFO - 'SHELL': '/bin/bash', 18:10:16 INFO - 'SHLVL': '1', 18:10:16 INFO - 'TERM': 'linux', 18:10:16 INFO - 'TMOUT': '86400', 18:10:16 INFO - 'USER': 'cltbld', 18:10:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:16 INFO - '_': '/tools/buildbot/bin/python'} 18:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:16 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 18:10:16 INFO - Cleaning up... 18:10:16 INFO - Return code: 0 18:10:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:10:16 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')]} 18:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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')]} 18:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:16 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 18:10:16 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 18:10:16 INFO - Using env: {'DISPLAY': ':0', 18:10:16 INFO - 'HOME': '/home/cltbld', 18:10:16 INFO - 'LANG': 'en_US.UTF-8', 18:10:16 INFO - 'LANGUAGE': 'en_US:en', 18:10:16 INFO - 'LOGNAME': 'cltbld', 18:10:16 INFO - 'MAIL': '/var/mail/cltbld', 18:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:16 INFO - 'MOZ_NO_REMOTE': '1', 18:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:16 INFO - 'NO_EM_RESTART': '1', 18:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:16 INFO - 'PWD': '/builds/slave/test', 18:10:16 INFO - 'SHELL': '/bin/bash', 18:10:16 INFO - 'SHLVL': '1', 18:10:16 INFO - 'TERM': 'linux', 18:10:16 INFO - 'TMOUT': '86400', 18:10:16 INFO - 'USER': 'cltbld', 18:10:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:16 INFO - '_': '/tools/buildbot/bin/python'} 18:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:16 INFO - Downloading/unpacking psutil>=3.1.1 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:10:18 INFO - Installing collected packages: psutil 18:10:18 INFO - Running setup.py install for psutil 18:10:18 INFO - building 'psutil._psutil_linux' extension 18:10:18 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 18:10:18 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 18:10:18 INFO - building 'psutil._psutil_posix' extension 18:10:18 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 18:10:18 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 18:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:10:19 INFO - Successfully installed psutil 18:10:19 INFO - Cleaning up... 18:10:19 INFO - Return code: 0 18:10:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:10:19 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')]} 18:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 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')]} 18:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:19 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 18:10:19 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 18:10:19 INFO - Using env: {'DISPLAY': ':0', 18:10:19 INFO - 'HOME': '/home/cltbld', 18:10:19 INFO - 'LANG': 'en_US.UTF-8', 18:10:19 INFO - 'LANGUAGE': 'en_US:en', 18:10:19 INFO - 'LOGNAME': 'cltbld', 18:10:19 INFO - 'MAIL': '/var/mail/cltbld', 18:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:19 INFO - 'MOZ_NO_REMOTE': '1', 18:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:19 INFO - 'NO_EM_RESTART': '1', 18:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:19 INFO - 'PWD': '/builds/slave/test', 18:10:19 INFO - 'SHELL': '/bin/bash', 18:10:19 INFO - 'SHLVL': '1', 18:10:19 INFO - 'TERM': 'linux', 18:10:19 INFO - 'TMOUT': '86400', 18:10:19 INFO - 'USER': 'cltbld', 18:10:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:19 INFO - '_': '/tools/buildbot/bin/python'} 18:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:10:21 INFO - Installing collected packages: mozsystemmonitor 18:10:21 INFO - Running setup.py install for mozsystemmonitor 18:10:21 INFO - Successfully installed mozsystemmonitor 18:10:21 INFO - Cleaning up... 18:10:21 INFO - Return code: 0 18:10:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:21 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:21 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 18:10:21 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 18:10:21 INFO - Using env: {'DISPLAY': ':0', 18:10:21 INFO - 'HOME': '/home/cltbld', 18:10:21 INFO - 'LANG': 'en_US.UTF-8', 18:10:21 INFO - 'LANGUAGE': 'en_US:en', 18:10:21 INFO - 'LOGNAME': 'cltbld', 18:10:21 INFO - 'MAIL': '/var/mail/cltbld', 18:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:21 INFO - 'MOZ_NO_REMOTE': '1', 18:10:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:21 INFO - 'NO_EM_RESTART': '1', 18:10:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:21 INFO - 'PWD': '/builds/slave/test', 18:10:21 INFO - 'SHELL': '/bin/bash', 18:10:21 INFO - 'SHLVL': '1', 18:10:21 INFO - 'TERM': 'linux', 18:10:21 INFO - 'TMOUT': '86400', 18:10:21 INFO - 'USER': 'cltbld', 18:10:21 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:21 INFO - '_': '/tools/buildbot/bin/python'} 18:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:21 INFO - Downloading/unpacking jsonschema==2.5.1 18:10:21 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 18:10:21 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 18:10:21 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 18:10:21 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 18:10:21 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 18:10:21 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 18:10:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:10:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:23 INFO - Downloading functools32-3.2.3-2.tar.gz 18:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:10:23 INFO - warning: no files found matching '*.txt' 18:10:23 INFO - no previously-included directories found matching 'build' 18:10:23 INFO - no previously-included directories found matching 'dist' 18:10:23 INFO - no previously-included directories found matching '.git*' 18:10:23 INFO - Installing collected packages: jsonschema, functools32 18:10:24 INFO - Running setup.py install for functools32 18:10:24 INFO - warning: no files found matching '*.txt' 18:10:24 INFO - no previously-included directories found matching 'build' 18:10:24 INFO - no previously-included directories found matching 'dist' 18:10:24 INFO - no previously-included directories found matching '.git*' 18:10:24 INFO - Successfully installed jsonschema functools32 18:10:24 INFO - Cleaning up... 18:10:24 INFO - Return code: 0 18:10:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:24 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:24 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 18:10:24 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 18:10:24 INFO - Using env: {'DISPLAY': ':0', 18:10:24 INFO - 'HOME': '/home/cltbld', 18:10:24 INFO - 'LANG': 'en_US.UTF-8', 18:10:24 INFO - 'LANGUAGE': 'en_US:en', 18:10:24 INFO - 'LOGNAME': 'cltbld', 18:10:24 INFO - 'MAIL': '/var/mail/cltbld', 18:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:24 INFO - 'MOZ_NO_REMOTE': '1', 18:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:24 INFO - 'NO_EM_RESTART': '1', 18:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:24 INFO - 'PWD': '/builds/slave/test', 18:10:24 INFO - 'SHELL': '/bin/bash', 18:10:24 INFO - 'SHLVL': '1', 18:10:24 INFO - 'TERM': 'linux', 18:10:24 INFO - 'TMOUT': '86400', 18:10:24 INFO - 'USER': 'cltbld', 18:10:24 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:24 INFO - '_': '/tools/buildbot/bin/python'} 18:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:10:24 INFO - Cleaning up... 18:10:24 INFO - Return code: 0 18:10:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:24 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:24 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 18:10:24 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 18:10:24 INFO - Using env: {'DISPLAY': ':0', 18:10:24 INFO - 'HOME': '/home/cltbld', 18:10:24 INFO - 'LANG': 'en_US.UTF-8', 18:10:24 INFO - 'LANGUAGE': 'en_US:en', 18:10:24 INFO - 'LOGNAME': 'cltbld', 18:10:24 INFO - 'MAIL': '/var/mail/cltbld', 18:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:24 INFO - 'MOZ_NO_REMOTE': '1', 18:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:24 INFO - 'NO_EM_RESTART': '1', 18:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:24 INFO - 'PWD': '/builds/slave/test', 18:10:24 INFO - 'SHELL': '/bin/bash', 18:10:24 INFO - 'SHLVL': '1', 18:10:24 INFO - 'TERM': 'linux', 18:10:24 INFO - 'TMOUT': '86400', 18:10:24 INFO - 'USER': 'cltbld', 18:10:24 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:24 INFO - '_': '/tools/buildbot/bin/python'} 18:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:24 INFO - Downloading/unpacking blobuploader==1.2.4 18:10:24 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 18:10:24 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 18:10:24 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 18:10:24 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 18:10:24 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 18:10:24 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 18:10:26 INFO - Downloading blobuploader-1.2.4.tar.gz 18:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:10:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:10:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:27 INFO - Downloading docopt-0.6.1.tar.gz 18:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:10:27 INFO - Installing collected packages: blobuploader, requests, docopt 18:10:27 INFO - Running setup.py install for blobuploader 18:10:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:10:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:10:27 INFO - Running setup.py install for requests 18:10:27 INFO - Running setup.py install for docopt 18:10:27 INFO - Successfully installed blobuploader requests docopt 18:10:27 INFO - Cleaning up... 18:10:28 INFO - Return code: 0 18:10:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:28 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:28 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 18:10:28 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 18:10:28 INFO - Using env: {'DISPLAY': ':0', 18:10:28 INFO - 'HOME': '/home/cltbld', 18:10:28 INFO - 'LANG': 'en_US.UTF-8', 18:10:28 INFO - 'LANGUAGE': 'en_US:en', 18:10:28 INFO - 'LOGNAME': 'cltbld', 18:10:28 INFO - 'MAIL': '/var/mail/cltbld', 18:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:28 INFO - 'MOZ_NO_REMOTE': '1', 18:10:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:28 INFO - 'NO_EM_RESTART': '1', 18:10:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:28 INFO - 'PWD': '/builds/slave/test', 18:10:28 INFO - 'SHELL': '/bin/bash', 18:10:28 INFO - 'SHLVL': '1', 18:10:28 INFO - 'TERM': 'linux', 18:10:28 INFO - 'TMOUT': '86400', 18:10:28 INFO - 'USER': 'cltbld', 18:10:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:28 INFO - '_': '/tools/buildbot/bin/python'} 18:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:28 INFO - Running setup.py (path:/tmp/pip-0FZ6q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:28 INFO - Running setup.py (path:/tmp/pip-wpec1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:28 INFO - Running setup.py (path:/tmp/pip-AJMbQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:28 INFO - Running setup.py (path:/tmp/pip-TAvUwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:28 INFO - Running setup.py (path:/tmp/pip-8oXEFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:28 INFO - Running setup.py (path:/tmp/pip-ku1R17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:28 INFO - Running setup.py (path:/tmp/pip-z2iNvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:28 INFO - Running setup.py (path:/tmp/pip-ekdkpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:28 INFO - Running setup.py (path:/tmp/pip-mTprDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:28 INFO - Running setup.py (path:/tmp/pip-LQzTeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:28 INFO - Running setup.py (path:/tmp/pip-8d9WJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:29 INFO - Running setup.py (path:/tmp/pip-IppKMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:29 INFO - Running setup.py (path:/tmp/pip-8a0HBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:29 INFO - Running setup.py (path:/tmp/pip-ElyOml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:29 INFO - Running setup.py (path:/tmp/pip-mok47K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:29 INFO - Running setup.py (path:/tmp/pip-7LJVSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:29 INFO - Running setup.py (path:/tmp/pip-Gg3k_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:10:29 INFO - Running setup.py install for manifestparser 18:10:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:10:29 INFO - Running setup.py install for mozcrash 18:10:29 INFO - Running setup.py install for mozdebug 18:10:29 INFO - Running setup.py install for mozdevice 18:10:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:10:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:10:29 INFO - Running setup.py install for mozfile 18:10:29 INFO - Running setup.py install for mozhttpd 18:10:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozinfo 18:10:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozInstall 18:10:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozleak 18:10:30 INFO - Running setup.py install for mozlog 18:10:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for moznetwork 18:10:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozprocess 18:10:30 INFO - Running setup.py install for mozprofile 18:10:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozrunner 18:10:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:10:30 INFO - Running setup.py install for mozscreenshot 18:10:31 INFO - Running setup.py install for moztest 18:10:31 INFO - Running setup.py install for mozversion 18:10:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:10:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:10:31 INFO - Cleaning up... 18:10:31 INFO - Return code: 0 18:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:31 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:31 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 18:10:31 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 18:10:31 INFO - Using env: {'DISPLAY': ':0', 18:10:31 INFO - 'HOME': '/home/cltbld', 18:10:31 INFO - 'LANG': 'en_US.UTF-8', 18:10:31 INFO - 'LANGUAGE': 'en_US:en', 18:10:31 INFO - 'LOGNAME': 'cltbld', 18:10:31 INFO - 'MAIL': '/var/mail/cltbld', 18:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:31 INFO - 'MOZ_NO_REMOTE': '1', 18:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:31 INFO - 'NO_EM_RESTART': '1', 18:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:31 INFO - 'PWD': '/builds/slave/test', 18:10:31 INFO - 'SHELL': '/bin/bash', 18:10:31 INFO - 'SHLVL': '1', 18:10:31 INFO - 'TERM': 'linux', 18:10:31 INFO - 'TMOUT': '86400', 18:10:31 INFO - 'USER': 'cltbld', 18:10:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:31 INFO - '_': '/tools/buildbot/bin/python'} 18:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:31 INFO - Running setup.py (path:/tmp/pip-VmAKa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:31 INFO - Running setup.py (path:/tmp/pip-jn_LGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:31 INFO - Running setup.py (path:/tmp/pip-Z1htIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:31 INFO - Running setup.py (path:/tmp/pip-uOx_ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:31 INFO - Running setup.py (path:/tmp/pip-CupbUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:31 INFO - Running setup.py (path:/tmp/pip-OvrYqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:31 INFO - Running setup.py (path:/tmp/pip-cfAZBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:31 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)) 18:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:31 INFO - Running setup.py (path:/tmp/pip-jgA0GC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:32 INFO - Running setup.py (path:/tmp/pip-_vq85h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:32 INFO - Running setup.py (path:/tmp/pip-ZVaPVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:32 INFO - Running setup.py (path:/tmp/pip-mxWIxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:32 INFO - Running setup.py (path:/tmp/pip-1zOsuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:32 INFO - Running setup.py (path:/tmp/pip-jx4M6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:32 INFO - Running setup.py (path:/tmp/pip-y2C7C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:32 INFO - Running setup.py (path:/tmp/pip-2dszZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:32 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)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:32 INFO - Running setup.py (path:/tmp/pip-k8lxx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:32 INFO - Running setup.py (path:/tmp/pip-CrB7J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:32 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)) 18:10:32 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)) 18:10:32 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)) 18:10:32 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)) 18:10:32 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)) 18:10:32 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)) 18:10:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:32 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 18:10:32 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 18:10:32 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 18:10:32 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 18:10:32 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 18:10:32 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 18:10:34 INFO - Downloading blessings-1.6.tar.gz 18:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:10:34 INFO - Installing collected packages: blessings 18:10:34 INFO - Running setup.py install for blessings 18:10:34 INFO - Successfully installed blessings 18:10:34 INFO - Cleaning up... 18:10:34 INFO - Return code: 0 18:10:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:35 INFO - Reading from file tmpfile_stdout 18:10:35 INFO - Current package versions: 18:10:35 INFO - argparse == 1.2.1 18:10:35 INFO - blessings == 1.6 18:10:35 INFO - blobuploader == 1.2.4 18:10:35 INFO - docopt == 0.6.1 18:10:35 INFO - functools32 == 3.2.3-2 18:10:35 INFO - jsonschema == 2.5.1 18:10:35 INFO - manifestparser == 1.1 18:10:35 INFO - mozInstall == 1.12 18:10:35 INFO - mozcrash == 1.0 18:10:35 INFO - mozdebug == 0.1 18:10:35 INFO - mozdevice == 0.49 18:10:35 INFO - mozfile == 1.2 18:10:35 INFO - mozhttpd == 0.7 18:10:35 INFO - mozinfo == 0.9 18:10:35 INFO - mozleak == 0.1 18:10:35 INFO - mozlog == 3.4 18:10:35 INFO - moznetwork == 0.27 18:10:35 INFO - mozprocess == 0.23 18:10:35 INFO - mozprofile == 0.28 18:10:35 INFO - mozrunner == 6.13 18:10:35 INFO - mozscreenshot == 0.1 18:10:35 INFO - mozsystemmonitor == 0.3 18:10:35 INFO - moztest == 0.7 18:10:35 INFO - mozversion == 1.4 18:10:35 INFO - psutil == 3.1.1 18:10:35 INFO - requests == 1.2.3 18:10:35 INFO - wsgiref == 0.1.2 18:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10: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')]} 18:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10: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')]} 18:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:35 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 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:35 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 18:10:35 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 18:10:35 INFO - Using env: {'DISPLAY': ':0', 18:10:35 INFO - 'HOME': '/home/cltbld', 18:10:35 INFO - 'LANG': 'en_US.UTF-8', 18:10:35 INFO - 'LANGUAGE': 'en_US:en', 18:10:35 INFO - 'LOGNAME': 'cltbld', 18:10:35 INFO - 'MAIL': '/var/mail/cltbld', 18:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:35 INFO - 'MOZ_NO_REMOTE': '1', 18:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:35 INFO - 'NO_EM_RESTART': '1', 18:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:35 INFO - 'PWD': '/builds/slave/test', 18:10:35 INFO - 'SHELL': '/bin/bash', 18:10:35 INFO - 'SHLVL': '1', 18:10:35 INFO - 'TERM': 'linux', 18:10:35 INFO - 'TMOUT': '86400', 18:10:35 INFO - 'USER': 'cltbld', 18:10:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:35 INFO - '_': '/tools/buildbot/bin/python'} 18:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 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)) 18:10:35 INFO - Cleaning up... 18:10:35 INFO - Return code: 0 18:10:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:10: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')]} 18:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10: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')]} 18:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa85f99ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9c120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', '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 18:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:10:35 INFO - Using env: {'DISPLAY': ':0', 18:10:35 INFO - 'HOME': '/home/cltbld', 18:10:35 INFO - 'LANG': 'en_US.UTF-8', 18:10:35 INFO - 'LANGUAGE': 'en_US:en', 18:10:35 INFO - 'LOGNAME': 'cltbld', 18:10:35 INFO - 'MAIL': '/var/mail/cltbld', 18:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:35 INFO - 'MOZ_NO_REMOTE': '1', 18:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:35 INFO - 'NO_EM_RESTART': '1', 18:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:35 INFO - 'PWD': '/builds/slave/test', 18:10:35 INFO - 'SHELL': '/bin/bash', 18:10:35 INFO - 'SHLVL': '1', 18:10:35 INFO - 'TERM': 'linux', 18:10:35 INFO - 'TMOUT': '86400', 18:10:35 INFO - 'USER': 'cltbld', 18:10:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:35 INFO - '_': '/tools/buildbot/bin/python'} 18:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:10:35 INFO - Cleaning up... 18:10:35 INFO - Return code: 0 18:10:35 INFO - Running post-action listener: _resource_record_post_action 18:10:35 INFO - Running post-action listener: _start_resource_monitoring 18:10:35 INFO - Starting resource monitoring. 18:10:35 INFO - [mozharness: 2016-12-15 02:10:35.663542Z] Finished create-virtualenv step (success) 18:10:35 INFO - [mozharness: 2016-12-15 02:10:35.664066Z] Running install step. 18:10:35 INFO - Running pre-action listener: _resource_record_pre_action 18:10:35 INFO - Running main action method: install 18:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:35 INFO - Reading from file tmpfile_stdout 18:10:35 INFO - Detecting whether we're running mozinstall >=1.0... 18:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:10:35 INFO - Reading from file tmpfile_stdout 18:10:35 INFO - Output received: 18:10:35 INFO - Usage: mozinstall [options] installer 18:10:35 INFO - Options: 18:10:35 INFO - -h, --help show this help message and exit 18:10:35 INFO - -d DEST, --destination=DEST 18:10:35 INFO - Directory to install application into. [default: 18:10:35 INFO - "/builds/slave/test"] 18:10:35 INFO - --app=APP Application being installed. [default: firefox] 18:10:35 INFO - mkdir: /builds/slave/test/build/application 18:10:35 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'] 18:10:35 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 18:10:52 INFO - Reading from file tmpfile_stdout 18:10:52 INFO - Output received: 18:10:52 INFO - /builds/slave/test/build/application/firefox/firefox 18:10:52 INFO - Running post-action listener: _resource_record_post_action 18:10:52 INFO - [mozharness: 2016-12-15 02:10:52.947966Z] Finished install step (success) 18:10:52 INFO - [mozharness: 2016-12-15 02:10:52.948206Z] Running run-tests step. 18:10:52 INFO - Running pre-action listener: _resource_record_pre_action 18:10:52 INFO - Running main action method: run_tests 18:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:10:52 INFO - Python 2.7.3 18:10:52 INFO - Return code: 0 18:10:52 INFO - grabbing minidump binary from tooltool 18:10:52 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')]} 18:10:52 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 0xf2cf20>, '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 0xe99c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe99f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:10:52 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 18:10:52 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 18:10:52 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 18:10:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:10:53 INFO - Return code: 0 18:10:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:10:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:10:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:10:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:10:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:10:53 INFO - Using env: {'DISPLAY': ':0', 18:10:53 INFO - 'HOME': '/home/cltbld', 18:10:53 INFO - 'LANG': 'en_US.UTF-8', 18:10:53 INFO - 'LANGUAGE': 'en_US:en', 18:10:53 INFO - 'LOGNAME': 'cltbld', 18:10:53 INFO - 'MAIL': '/var/mail/cltbld', 18:10:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:10:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:53 INFO - 'MOZ_NO_REMOTE': '1', 18:10:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:53 INFO - 'NO_EM_RESTART': '1', 18:10:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:53 INFO - 'PWD': '/builds/slave/test', 18:10:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:10:53 INFO - 'SHELL': '/bin/bash', 18:10:53 INFO - 'SHLVL': '1', 18:10:53 INFO - 'TERM': 'linux', 18:10:53 INFO - 'TMOUT': '86400', 18:10:53 INFO - 'USER': 'cltbld', 18:10:53 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510', 18:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:53 INFO - '_': '/tools/buildbot/bin/python'} 18:10:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:10:53 INFO - mozversion application_buildid: 20161214163014 18:10:53 INFO - mozversion application_changeset: b1ab720c6d3e412ede797b08dfe63dca170f6ee0 18:10:53 INFO - mozversion application_display_name: Nightly 18:10:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:10:53 INFO - mozversion application_name: Firefox 18:10:53 INFO - mozversion application_remotingname: firefox 18:10:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:10:53 INFO - mozversion application_vendor: Mozilla 18:10:53 INFO - mozversion application_version: 53.0a1 18:10:53 INFO - mozversion platform_buildid: 20161214163014 18:10:53 INFO - mozversion platform_changeset: b1ab720c6d3e412ede797b08dfe63dca170f6ee0 18:10:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:10:53 INFO - mozversion platform_version: 53.0a1 18:10:53 INFO - using testdate: 1481767853 18:10:53 INFO - actual date: 1481767853 18:10:53 INFO - starting webserver on 'localhost:59271' 18:10:53 INFO - SUITE-START | Running 2 tests 18:10:53 INFO - TEST-START | dromaeo_css 18:10:53 INFO - operating with platform_type : linux_ 18:10:53 INFO - Initialising browser for dromaeo_css test... 18:10:53 INFO - TEST-INFO | started process 21301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYKwsgk/profile http://localhost:59271/getInfo.html) 18:10:57 INFO - PROCESS | 21301 | __metrics Screen width/height:1600/1200 18:10:57 INFO - PROCESS | 21301 | colorDepth:24 18:10:57 INFO - PROCESS | 21301 | Browser inner width/height: 1024/697 18:10:57 INFO - PROCESS | 21301 | __metrics 18:10:58 INFO - PROCESS | 21301 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:10:58 INFO - PROCESS | 21301 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:10:58 INFO - PROCESS | 21301 | console.error: PushService: 18:10:58 INFO - PROCESS | 21301 | stateChangeProcessEnqueue: Error transitioning state 18:10:58 INFO - PROCESS | 21301 | UnknownError 18:10:59 INFO - TEST-INFO | 21301: exit 0 18:10:59 INFO - Browser initialized. 18:10:59 INFO - Running cycle 1/1 for dromaeo_css test... 18:10:59 INFO - TEST-INFO | started process 21384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYKwsgk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:11:11 INFO - PROCESS | 21384 | RSS: Main: 173068288 18:11:11 INFO - PROCESS | 21384 | 18:12:02 INFO - PROCESS | 21384 | 1481767922855 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:14:13 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59271/tests/dromaeo/cssquery-ext.html) 18:14:15 INFO - PROCESS | 21384 | RSS: Main: 294305792 18:14:15 INFO - PROCESS | 21384 | 18:17:17 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-ext.html (next: http://localhost:59271/tests/dromaeo/cssquery-jquery.html) 18:17:18 INFO - PROCESS | 21384 | RSS: Main: 287539200 18:17:18 INFO - PROCESS | 21384 | 18:20:20 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59271/tests/dromaeo/cssquery-mootools.html) 18:20:20 INFO - PROCESS | 21384 | RSS: Main: 263327744 18:20:20 INFO - PROCESS | 21384 | 18:23:22 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59271/tests/dromaeo/cssquery-prototype.html) 18:23:23 INFO - PROCESS | 21384 | RSS: Main: 219934720 18:23:23 INFO - PROCESS | 21384 | 18:26:26 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59271/tests/dromaeo/cssquery-yui.html) 18:26:26 INFO - PROCESS | 21384 | RSS: Main: 221179904 18:26:26 INFO - PROCESS | 21384 | 18:29:29 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:59271/tests/dromaeo/cssquery-yui.html (next: http://localhost:59271/tests/dromaeo/cssquery-dojo.html) 18:29:29 INFO - PROCESS | 21384 | RSS: Main: 315105280 18:29:29 INFO - PROCESS | 21384 | 18:29:29 INFO - PROCESS | 21384 | __start_tp_report 18:29:29 INFO - PROCESS | 21384 | _x_x_mozilla_page_load 18:29:29 INFO - PROCESS | 21384 | _x_x_mozilla_page_load_details 18:29:29 INFO - PROCESS | 21384 | |i|pagename|runs| 18:29:29 INFO - PROCESS | 21384 | |0;dojo.html;3023.4406634772567;3065.371598822241;3067.18719539322;3092.350606372662;3113.517404802255;5171.224316352549;5227.73861306935;5265.052290587692;5295.099833028385;5322.733863306834;5243.370795504536;5275.657082289648;5308.283381743464;5319.893602127955;5353.197020446935;4735.668503204777;4765.785539650707;4784.66507344486;4832.154372984731;4836.564709176174;4896.926546101811;4904.36243288372;4951.876203094934;4955;5000.249962505635;4732.006278681447;4837.975810120962;4838.274258861164;4848.296996935424;4942.604591632661;4753.47711751707;4832.951670483285;4838.201696720037;4848;4851.345068415754;4794.424669039703;4804.639652026112;4805.231163013936;4810.951890481086;4813.735244561548;5171.146760784466;5195.142801437758;5215.269862219292;5217.139172036647;5221.477852214786;5599.32808063035;5599.552035837164;5604.859878503029;5612.41069687681;5655.123455864347;5650.971745141248;5652.208690783294;5653.293338332708;5657.915131273035;5658.122990936411;5182.53357197854;5235.947640523622;5248.05535003703;5288.68267903927;5329.467053294639;5669.886602268014;5773.740181691834;5777.451142141574;5787.913181302284;5824.417558244227;5562.9443705563235;5568;5571.13647384656;5572.331320241516;5573.331200255995;5517.641353312022;5544.22380866679;5554.972225138848;5642;5642.7178641067785;5600.747966341443;5611.130274807411;5612.691301978431;5613.7193140342815;5638.774449021993;5506.449355064461;5520.944790552124;5551.167324901297;5649.745761440745;5660.377358490563;4285.078708077778;4320.913581728348;4330.523642399334;4334.263175260214;4340.565943405697;3849.5188101487315;3899.2981263372867;3917.118648304109;3936.2127574485216;3940;5282.8943421132135;5335.9466405336225;5338.332708411449;5338.412774594792;5341.732913354317;5635.971820140955;5658.066419040894;5661.433856614388;5661.603687741901;5664.320281566238;5242.7378631068295;5335.1730481775385;5339.652922560099;5343.091674415358;5344.038073146793;4848.5636292733825;4849.878753031202;4858.514148585184;4860.73265970375;4863.683860549053;5738.024535828919;5758.452946970031;5759.942400576025;5762.078067509262;5763.596548241667;5563.109902415756;5590.189422533791;5623.606347555632;5640.774369025193;5701.885962280814;5743.741531631003;5748.655080695172;5752.511036561772;5757.453041961007;5759.049756790083;5374.140137577969;5542.695151766691;5553.444655534414;5555.611107222456;5565.0817615094675;5489.066858634122;5583.218349431002;5628.802991895264;5629.071203251581;5747.482726554463;5555.749991250323;5559.110542313372;5569.053260945568;5573.303337082864;5574.470425309589;5485.6982865942755;5525.723713814374;5529.612927095065;5534;5550.639208451437;5352.170413585901;5375.650582712111;5415.729213539228;5419.05166595852;5423.945760542344;5586.245856809279;5586.553075754011;5587.357453892854;5590.692511911884;5616.691081990366;5453.618246722692;5460.726963651881;5461.322277809979;5465.125579907196;5469.097598896137 18:29:30 INFO - PROCESS | 21384 | |1;ext.html;539.5009616105102;552.4447929830521;553.6401339129562;567.5346216102797;569.3822202909844;31528.684713152863;39450;40937.3859392109;41231.17537649245;44590.7770461148;42764.572354276526;43068.353974690406;43180.78409607955;43198;43214.7839260804;7841.137474877759;8587.484750914966;8750.781230469225;8754.6935857245;8759.999999999967;7702.8844567331535;10129.088382045615;10368.11870990963;10369.014943580385;10657.200709946746;10415.218858585598;10471.84292235617;10479.05688488036;10480.633177838778;10487.370757754521;10255.641052563162;10321.690349289533;10359.430231337274;10360.378377297311;10372.11836993849;10231.13035391985;10305.948470257676;10308;10308.690739277834;10322.329048611817;39914.80042599768;40396;40406.19187616261;40407.595924040674;40483.7975810122;3212.646608873022;3222.7905186162825;3256.3812875553567;3386.8137252451106;3512.929741405183;30224.24439389011;30345.241368965733;30379.848100759355;30406.391872162432;30833;4069.3442403775107;4096.754194748325;4116.835326586933;4182.958170418287;4242.851500197509;324165;324433;324780;326090.9999999976;326343;55367;55379.72310138504;55407.722961384934;55573;55596.16605750917;58164;58217.41782582205;58265.417345826;58324.12513812296;58373.70813145992;12968.416421261067;13291.401886914919;13302.26837523926;13305.534306299232;13318.400671969785;9502.382345147544;9692.127708506268;10798.730031749112;10898.836517452244;11175.32948023105;15988.120653364176;16013.519594412186;16021.59946001336;16083.437079702155;16091.597710057342;5745.971270143623;5840.766369345263;5868.589198756132;5872.706364681749;5888.705564721747;882.5940067568862;948.3314263444394;1063.6649455421518;1064.9414282214398;1094.2777766673958;28419.28951776181;29949.700502994692;30068.849655751583;30298.091057267877;30467;1019.3221507697298;1025.6153942271649;1056.6671498478108;1073.8765042020268;1099.9230053896151;15188.924055379874;15811.288492017657;15908;15998.280077396545;16151;426064;428926;431624;432436;432533;55059.72470137704;55111.44888551063;55153.72423137858;55207.44792552103;55330.17004744932;1099.9780004399868;1134.466800603715;1227.2084505494086;1249.450241893564;1277.2400421749044;27172.32069198286;30483.390332193674;30640.84679576677;30827.537586935767;31212.843935780176;46931.53068469271;47751;47769.52230477651;47835.28247076227;47861.76069119632;357274;359397;359621;359945;360609;24594;24828.627570586817;25261.326654568962;25808.741912580634;39077.6092239074;40346;40633.84548154082;40838.59161408348;41179.38230926479;41678.79160604178;34974;35065.824670876485;35515;35573.82213088918;35740.10649733777;34912;35551.11122221965;35619;35707.82146089253;35771.28457430889;33853.15367115842;34291.4856277161;34350.48474272938;35084.64915350814;35087.47368789521;29896.850515747283;32356.514652279762;32363.67636323701;32366.352672945937;32593.34813303768;24498.21759731174;24657.137000204908;24694.382640434134;24760;24927.501449971263 18:29:30 INFO - PROCESS | 21384 | |2;jquery.html;365.7220512410567;378.2265267527907;379.00585375533274;384.77875221747496;409.5699515508715;14568;15003.324850381732;17715.202815873283;18501;18541.907290463547;16590.66818663626;16620.418285360014;16863.91568042161;16949.237284322204;16971.860205861925;3685.981570092146;5426.538744206738;5663.830085097454;5682.26130603021;5687.118496633028;3687.1703866630064;5908.61593996391;6145.723442445078;6176.938230617681;6280.371962803729;6041.395860413945;6230.376962303779;6238.03310486873;6238.407351301619;6240.937590624104;6211.844703882416;6231.688415579203;6249.031400132987;6249.812505624793;6259.906101408483;5332.946670533283;5456.072467680504;5494.2582751328855;5499.422560631157;5533.08704063829;21673.783262167493;21699.891500542555;21774.237901673365;22012.239020878453;22191.66712499314;2083.7186979757844;2310.5263420998654;2327.976720232793;2505.8120640952;2597.0261152067974;9150.267978561698;9179.586918588613;9324.8601270981;9410.247180225633;9511.857322140173;1806.1872157529033;1857.8142185781448;1928.6528424883516;1935.5354714868472;1989.055198780582;73422.63288683523;77519;80403.5979820103;81633;81799.5910020458;10464.790704186025;10529.631462898915;10533.157347412189;10719;10934.179936504794;14790.408383664748;14800;14810.185439800698;14828.481003164838;14832.18422986746;6895.379585797334;7106.431485481149;7234.204237533867;7330.560166390033;7342.632868356561;6647.867042659216;6665.833354166088;6837.316268373223;6886.586804791728;6891.931080689229;4163.354680024601;4220.430241917364;4419.558044195619;4623.768811559408;4679.040796636681;1854.7403363529118;1884.7361369408293;1888.1408958923657;1893.2994791927076;1903.7620297462818;787.9606019698992;863.96976105836;867.6919693508839;879.9912000880038;973.3089506450358;17009.319627214772;18236.346732512964;18585.5353616158;18693.345732899284;19510;892.2772554231094;952.547190983221;957.698325027628;993.6621548673486;1042.1818872185297;10016.248781341428;10180.440075795754;10374.844377334346;10489.108425783741;10496.475176241158;389086;390057.0302469336;397501;397995;400927;82282;82358;85114.14885851186;86264;88324;952.3857112162755;984.1880448629909;994.4878387630376;1002.0330381182195;1010.4846528270488;15900.68198636044;16215.513534593982;16688.666226675643;16729.33082676728;17064.65870682604;39242.607573923895;39285.80357098196;39439.408408874464;39998.2000359997;41286.38070428886;53350;53817.46182538281;53854.73072634612;55263;56367.71816140893;7495.43784216175;7655.311022008046;7765.844683106419;7854.546908821886;7996.24035716606;9244.491552964382;11918.582849600223;12010.879891201326;12131.878681213428;12139.575114870937;10420.426876521864;10601.151907847508;10836.945815270874;11056.281341713086;11294.94352528232;9891.159251463418;10193.337433066827;10327;10357.585696572052;10968.396738179477;10799.892001079888;10857.34855908648;10880.183986201226;10897.237193396386;11341.603043893425;7603.581803000888;7638.656260468181;7730.845383092419;7916.802079947817;7943.44395892282;10218.335808172445;10856.294340867818;10858;10884.12926965825;11264.831027534758 18:29:30 INFO - PROCESS | 21384 | |3;mootools.html;1267.8858902698755;1366.6105160029392;1387.1330418488444;1489.4116823854579;1704.4971733338657;6900.482463815215;7444.478886477942;7740.806479838006;7767.805804854867;7792.181820908808;7640.961795191016;7759.340456061227;7772.067351917778;7953.920460795391;7962.243586859254;1845.815418458157;1900.9714854277197;2073.2536286936693;2118.1739121742535;2127.723395958531;2053.2505635443104;2110.8205802506805;2115.5134319106537;2125.224293133003;2131.5523740014537;2084.864483808555;2100.4433825036376;2101.695254188142;2108.399106254718;2116.703661487393;2100.065470865458;2122.7983341582526;2123.1719629344643;2123.936281911545;2125.08621292844;2079.0852025109057;2121.1621409543145;2121.289368061701;2125.6811478278228;2125.691774692661;7673.577953212571;7742.574158421285;7845.0585929688805;7890.408219383569;7926.722564710265;4607.608353289975;4724.503927087675;4726.4328280606205;4734.928976065362;4743.24108142699;3038.2100653829943;3199.104250809777;3199.7120259176786;3201.983990080058;3207.8075315481146;993.2897977945803;1000.2148313573882;1028.8611037509916;1043.9112675422598;1044.5038606661851;10468.79062418755;10781.676549703521;10795.406252656101;10803.297785643908;10807.837882431691;3901.356276214393;4018.9196216076098;4126.133511962462;4194.643455306272;4201.222773786859;9132.3150763693;9141.451512909247;9148.634054637872;9149.268058555299;9151.35940484173;4717.764111794397;4747.881302967385;4748.026654535812;4753.786079626425;4755.096531658974;3954.525456945185;3992.5608183099835;4377.9781101094295;4411;4412.227860124465;3518.3666939950544;3615.403458429382;3656.7805931643657;3677.3013127505687;3695.667389934919;1142.7485953090306;1171.9941400293114;1182.2079206931376;1182.9704257393462;1195.1096433157354;760.5436737957182;905.9264771246094;908.3505293715026;933.4306073295285;934.4486752815872;5245.711485868237;5284.101702710548;5345.572354211654;5455.699936503451;5479.863003424946;393.6004954970707;398.1559094719156;399.6023956163578;401.70675406952506;404.0283119098573;3645.927081458356;3703.3889408247337;3767.472553842464;3778.7732736035928;3873.4577159197734;9224.584893679801;9466.621335146654;9688.70933871985;9723.367981081206;9744.269179811543;9634.373765705206;9638.566264517973;9643.035696430441;9645.938946715856;9656.565454554562;6978.337057979484;7204.891926621105;7258.129024517092;7263.3826124778925;7374.410047196318;5349.759260833194;5418.891622167614;5513.283273174462;5611.551075913999;5694;8745.207232517447;8798.516081615573;8838.337124715801;8868.246199073152;8878.51168185756;8972.955135224543;8992.100789921216;9002.459852408876;9109.498977556297;9126.178643921814;6241.063840423973;6416.839579010375;6834.692438840165;6856.417204537471;7086.50394472382;7473.8878916817375;7743.5353878767455;7745.341645960157;7747.72882949094;7749.845003100019;6892.586444813327;7059.71761129549;7070.257622949516;7116.430685545248;7202.675879585536;6887;6909.412699920362;6912.723491060301;6983.860322793617;6985.685644145924;5757.97121014409;5860.38465961085;5908.084246941731;5986.491148252448;5993.490553303018;4459.2876903521155;4547.3633691282585;4593.494715581351;4658;4738.431388233434;4678.719276843264;4699.130660827758;4710.105080034782;4780.474147843804;4858.8299409521915 18:29:30 INFO - PROCESS | 21384 | |4;prototype.html;351.1116874308001;353.3586540429121;355.59284619111116;358.7452908435011;361.400075874049;5155.252488389181;5164.199549069891;5173.172292433207;5175.508326708966;5213.556847667953;5121.359830021247;5137.537621614054;5175.1461008933575;5175.663581867176;5177.818776342829;3758.868439604614;3871.2451072040963;4045.1707399982934;4051.149258655686;4075.9592404075875;4062.9593704063022;4065.4714887064642;4069.2471892699946;4077.8776636700945;4088.897777555555;4036.495438070241;4040.5757395473493;4044.3326851069537;4045.59544045596;4055.9797201013953;3825.61743825618;3871.380579107329;3951.9802400987955;4040.393940908858;4047.0084829216735;3592.4252119660728;3603.1532589841368;3616.566012078541;3629.364861149288;3630.146915474861;5005.599552035828;5009.84773502093;5031.748412579356;5046.7224302663335;5072.51811077947;4306.332518459643;4452.621527170194;4482.394876691637;4679.649026323005;4683.180443422421;5342.4322204514965;5349.528879558114;5351.411344752074;5362.91955620666;5366.973165134188;3745.232227393405;3753.680937120348;3762.2287431076566;3766.9075967969256;3774.2640185163905;5452.454754524555;5535.308086489189;5558.333000039981;5570.635194377403;5593.720313984284;3863.227354529049;3871.9612803872164;3878.7284890057426;3882.2235552889533;3889.463661853552;3876.5348158221195;3919.784411857318;3919.981209677019;3921.6274453927404;3927;4388;4514.322851572225;4516.254817955;4532.750698711537;4534.4129554656265;5383.461653834585;5387.4073851876265;5405.675609475704;5405.8107966221;5414.675119492843;1310.8885744711615;1311.4459365651733;1317.8879795217515;1319.8284223050941;1320.80187971805;864.8659457784054;905.4160066756905;908.4231512989168;911.4667919267303;911.9908800912038;5039.899202016012;5089.600359900983;5162.845114646567;5245.606579506552;5254.15933450646;5531.280933478622;5554.361248456398;5555.472230138131;5557.138643510262;5565.465929135028;5122.820701275437;5243.23973023917;5258.132408152688;5270.5256526912;5279.947200527946;2812.831230126199;2848.4018356145443;2850.2589326774773;2851.458222937635;2860.8140470869325;5525.64621667694;5536.335639723259;5576.302962129734;5582.91625625616;5586.245856809279;5245.816396426107;5501.092319767274;5510.448955104457;5512.751926163252;5523.1991361253195;5448.128299472065;5459.00746227632;5469.917951230735;5525.0883604204855;5580.553555715533;5301.448029819952;5316.102724015498;5318.8458674237;5342.717747740437;5396.622236443412;5172.327597412313;5500.67983683921;5570.749316280858;5591.916121258266;5592.189132575672;5770.221020162392;5776.595638305278;5779.8555036124435;5786.681732504752;5789.884202316014;3737.5141231639714;3948.9605103948593;3957.4855268816023;3965.425013372987;3974.602539746002;5489.64317319373;5511.53151982086;5527.8894422112135;5531.115021596525;5533.806316778893;5359.866003349947;5381.555804285339;5392.649477783932;5397.892042159214;5405.8918821622565;5145.202493613496;5293.920591191212;5332.920315958372;5347.839564813062;5357.330333708286;5255.947440525546;5267.104592219255;5285.260063591023;5286.20706893969;5287.180487024518;5184.403793563638;5290.619221350331;5291.9470805292995;5349.144136938071;5447.319085114361;5286.810467644749;5302.416734159317;5315.176147697113;5340.477963780304;5370.677759334452 18:29:30 INFO - PROCESS | 21384 | |5;yui.html;655.285738544986;748.5321673953781;750.1298493747254;763.7023737096446;784.8391079828635;14722.337494812733;16007.519774406772;16164.83835161648;16194.595135121628;16326.265318060687;14537.85462145381;14801.037932534404;14902.40390384386;14961.251937403113;14969.850301496981;789.4592204340016;803.618281316373;804.9436539442212;805.4080251015499;806.5725165662209;670.2627110178813;699.3076853914639;760.3992845651928;779.4504874063772;786.0096278688836;836.6695155413609;837.5519097282955;837.8701301298308;837.9287760540361;838.1074156023815;831.8170002599419;832.4131487301438;833.4374297349265;833.62903507939;834.7620928035511;518.3624142304986;534.5910378560404;535.253321616343;537.720385399594;589.4900910712203;14278.428862845474;14933.253337333199;14941.477048303257;14953.626159345995;14957.850421495863;12690.682732931751;12781.297028663419;12903.0322725795;12909.67725806853;12999.545015924397;10927.125829933666;11125.666230013112;11389.316641001566;11680;11737.647870563897;3863.2466668999555;3908.4332771748223;3927.7643341399607;3929.135590170158;3978.4827972363696;79514;81131.5943420285;83258.58370708168;86468;87606.5619671904;9445.244380449685;9449.858252126223;9688;9837.803243935225;9847.704568862946;18524.536886577942;18656.440306790817;21335.78664213369;21818.236361727264;22110.668339974913;7542.886856697154;7689.466170142632;7768.728094516778;7972.681092756341;8188.959055204686;4361.149575832682;4383.693141480066;4459.331100334975;4480.14877173336;4510.481294651157;2997.355568552728;3078.184281165493;3081.383723255358;3089.5211242257487;3098.7830851840604;548.9149181876816;555.5416781155562;560.9018421776173;562.5780664501624;570.823044856096;597.4413922982026;616.3189675408609;639.9360063993656;664.8736740019381;666.5867162359368;8240.909658877921;8422.915770842214;8966.955165224132;9021;9083.81832363349;630.8549033722178;647.3526473526474;692.7159864455549;695.5011949249375;709.6664567653195;4052.3516237402464;4097.160082183145;4257.808398622069;4259.936100958488;4309.353596960481;72003.6399818008;73573;75395.24604753991;76291;76360.61819690866;64981.35018649847;65365;65577.67211163914;67142;67431.66284168548;57565.71217143888;58172.70913645405;58314.7084264576;59321.88137423451;59472;7738.41961852852;8063.153368896416;8259.463134896212;8343.249107580346;8369.49783013021;41573.1685366297;41740.373894391;42505.57494425016;43200.35199472073;44255.55744442515;43776;54202;54830.45169548413;55270.72364638151;58307.70846145742;10718.785624287626;12235.69410764738;12241.632751017483;12301.507939682671;12758.48966041348;13222.918798620854;13544.390502427612;14010.579682609537;14212.857871421153;14786.334614942138;11479.36863472517;12251.571195008131;12353.505859765508;12756.298403587893;13212.735745285232;10397.465722912499;12401.813972790596;12569.874301257236;12772.936135319263;12863.163894346837;12182.817257741319;12284.570040048555;12541.184822986841;12631.494740210288;12639.241645501299;10237.692869213935;10557.20820938448;10832.566697332019;10914.345139291669;11431.314121152758;9801.411915285105;9814.263930205407;9908.306418550632;9936.354136981074;9945.751356216153 18:29:30 INFO - PROCESS | 21384 | __end_tp_report 18:29:30 INFO - PROCESS | 21384 | __start_cc_report 18:29:30 INFO - PROCESS | 21384 | _x_x_mozilla_cycle_collect,1069 18:29:30 INFO - PROCESS | 21384 | __end_cc_report 18:29:30 INFO - PROCESS | 21384 | __startTimestamp1481768969994__endTimestamp 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | ------- Summary: start ------- 18:29:30 INFO - PROCESS | 21384 | Number of tests: 6 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#0] dojo.html Cycles:165 Average:5238.59 Median:5460.73 stddev:575.48 (10.5%) stddev-sans-first:550.39 18:29:30 INFO - PROCESS | 21384 | Values: 3023.4 3065.4 3067.2 3092.4 3113.5 5171.2 5227.7 5265.1 5295.1 5322.7 5243.4 5275.7 5308.3 5319.9 5353.2 4735.7 4765.8 4784.7 4832.2 4836.6 4896.9 4904.4 4951.9 4955.0 5000.2 4732.0 4838.0 4838.3 4848.3 4942.6 4753.5 4833.0 4838.2 4848.0 4851.3 4794.4 4804.6 4805.2 4811.0 4813.7 5171.1 5195.1 5215.3 5217.1 5221.5 5599.3 5599.6 5604.9 5612.4 5655.1 5651.0 5652.2 5653.3 5657.9 5658.1 5182.5 5235.9 5248.1 5288.7 5329.5 5669.9 5773.7 5777.5 5787.9 5824.4 5562.9 5568.0 5571.1 5572.3 5573.3 5517.6 5544.2 5555.0 5642.0 5642.7 5600.7 5611.1 5612.7 5613.7 5638.8 5506.4 5520.9 5551.2 5649.7 5660.4 4285.1 4320.9 4330.5 4334.3 4340.6 3849.5 3899.3 3917.1 3936.2 3940.0 5282.9 5335.9 5338.3 5338.4 5341.7 5636.0 5658.1 5661.4 5661.6 5664.3 5242.7 5335.2 5339.7 5343.1 5344.0 4848.6 4849.9 4858.5 4860.7 4863.7 5738.0 5758.5 5759.9 5762.1 5763.6 5563.1 5590.2 5623.6 5640.8 5701.9 5743.7 5748.7 5752.5 5757.5 5759.0 5374.1 5542.7 5553.4 5555.6 5565.1 5489.1 5583.2 5628.8 5629.1 5747.5 5555.7 5559.1 5569.1 5573.3 5574.5 5485.7 5525.7 5529.6 5534.0 5550.6 5352.2 5375.7 5415.7 5419.1 5423.9 5586.2 5586.6 5587.4 5590.7 5616.7 5453.6 5460.7 5461.3 5465.1 5469.1 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#1] ext.html Cycles:180 Average:52713.37 Median:34320.99 stddev:98702.57 (287.6%) stddev-sans-first:98901.72 18:29:30 INFO - PROCESS | 21384 | Values: 539.5 552.4 553.6 567.5 569.4 31528.7 39450.0 40937.4 41231.2 44590.8 42764.6 43068.4 43180.8 43198.0 43214.8 7841.1 8587.5 8750.8 8754.7 8760.0 7702.9 10129.1 10368.1 10369.0 10657.2 10415.2 10471.8 10479.1 10480.6 10487.4 10255.6 10321.7 10359.4 10360.4 10372.1 10231.1 10305.9 10308.0 10308.7 10322.3 39914.8 40396.0 40406.2 40407.6 40483.8 3212.6 3222.8 3256.4 3386.8 3512.9 30224.2 30345.2 30379.8 30406.4 30833.0 4069.3 4096.8 4116.8 4183.0 4242.9 324165.0 324433.0 324780.0 326091.0 326343.0 55367.0 55379.7 55407.7 55573.0 55596.2 58164.0 58217.4 58265.4 58324.1 58373.7 12968.4 13291.4 13302.3 13305.5 13318.4 9502.4 9692.1 10798.7 10898.8 11175.3 15988.1 16013.5 16021.6 16083.4 16091.6 5746.0 5840.8 5868.6 5872.7 5888.7 882.6 948.3 1063.7 1064.9 1094.3 28419.3 29949.7 30068.8 30298.1 30467.0 1019.3 1025.6 1056.7 1073.9 1099.9 15188.9 15811.3 15908.0 15998.3 16151.0 426064.0 428926.0 431624.0 432436.0 432533.0 55059.7 55111.4 55153.7 55207.4 55330.2 1100.0 1134.5 1227.2 1249.5 1277.2 27172.3 30483.4 30640.8 30827.5 31212.8 46931.5 47751.0 47769.5 47835.3 47861.8 357274.0 359397.0 359621.0 359945.0 360609.0 24594.0 24828.6 25261.3 25808.7 39077.6 40346.0 40633.8 40838.6 41179.4 41678.8 34974.0 35065.8 35515.0 35573.8 35740.1 34912.0 35551.1 35619.0 35707.8 35771.3 33853.2 34291.5 34350.5 35084.6 35087.5 29896.9 32356.5 32363.7 32366.4 32593.3 24498.2 24657.1 24694.4 24760.0 24927.5 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#2] jquery.html Cycles:180 Average:25426.47 Median:39362.61 stddev:65604.32 (166.7%) stddev-sans-first:65761.38 18:29:30 INFO - PROCESS | 21384 | Values: 365.7 378.2 379.0 384.8 409.6 14568.0 15003.3 17715.2 18501.0 18541.9 16590.7 16620.4 16863.9 16949.2 16971.9 3686.0 5426.5 5663.8 5682.3 5687.1 3687.2 5908.6 6145.7 6176.9 6280.4 6041.4 6230.4 6238.0 6238.4 6240.9 6211.8 6231.7 6249.0 6249.8 6259.9 5332.9 5456.1 5494.3 5499.4 5533.1 21673.8 21699.9 21774.2 22012.2 22191.7 2083.7 2310.5 2328.0 2505.8 2597.0 9150.3 9179.6 9324.9 9410.2 9511.9 1806.2 1857.8 1928.7 1935.5 1989.1 73422.6 77519.0 80403.6 81633.0 81799.6 10464.8 10529.6 10533.2 10719.0 10934.2 14790.4 14800.0 14810.2 14828.5 14832.2 6895.4 7106.4 7234.2 7330.6 7342.6 6647.9 6665.8 6837.3 6886.6 6891.9 4163.4 4220.4 4419.6 4623.8 4679.0 1854.7 1884.7 1888.1 1893.3 1903.8 788.0 864.0 867.7 880.0 973.3 17009.3 18236.3 18585.5 18693.3 19510.0 892.3 952.5 957.7 993.7 1042.2 10016.2 10180.4 10374.8 10489.1 10496.5 389086.0 390057.0 397501.0 397995.0 400927.0 82282.0 82358.0 85114.1 86264.0 88324.0 952.4 984.2 994.5 1002.0 1010.5 15900.7 16215.5 16688.7 16729.3 17064.7 39242.6 39285.8 39439.4 39998.2 41286.4 53350.0 53817.5 53854.7 55263.0 56367.7 7495.4 7655.3 7765.8 7854.5 7996.2 9244.5 11918.6 12010.9 12131.9 12139.6 10420.4 10601.2 10836.9 11056.3 11294.9 9891.2 10193.3 10327.0 10357.6 10968.4 10799.9 10857.3 10880.2 10897.2 11341.6 7603.6 7638.7 7730.8 7916.8 7943.4 10218.3 10856.3 10858.0 10884.1 11264.8 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#3] mootools.html Cycles:180 Average:5154.26 Median:4754.44 stddev:2902.71 (61.1%) stddev-sans-first:2896.16 18:29:30 INFO - PROCESS | 21384 | Values: 1267.9 1366.6 1387.1 1489.4 1704.5 6900.5 7444.5 7740.8 7767.8 7792.2 7641.0 7759.3 7772.1 7953.9 7962.2 1845.8 1901.0 2073.3 2118.2 2127.7 2053.3 2110.8 2115.5 2125.2 2131.6 2084.9 2100.4 2101.7 2108.4 2116.7 2100.1 2122.8 2123.2 2123.9 2125.1 2079.1 2121.2 2121.3 2125.7 2125.7 7673.6 7742.6 7845.1 7890.4 7926.7 4607.6 4724.5 4726.4 4734.9 4743.2 3038.2 3199.1 3199.7 3202.0 3207.8 993.3 1000.2 1028.9 1043.9 1044.5 10468.8 10781.7 10795.4 10803.3 10807.8 3901.4 4018.9 4126.1 4194.6 4201.2 9132.3 9141.5 9148.6 9149.3 9151.4 4717.8 4747.9 4748.0 4753.8 4755.1 3954.5 3992.6 4378.0 4411.0 4412.2 3518.4 3615.4 3656.8 3677.3 3695.7 1142.7 1172.0 1182.2 1183.0 1195.1 760.5 905.9 908.4 933.4 934.4 5245.7 5284.1 5345.6 5455.7 5479.9 393.6 398.2 399.6 401.7 404.0 3645.9 3703.4 3767.5 3778.8 3873.5 9224.6 9466.6 9688.7 9723.4 9744.3 9634.4 9638.6 9643.0 9645.9 9656.6 6978.3 7204.9 7258.1 7263.4 7374.4 5349.8 5418.9 5513.3 5611.6 5694.0 8745.2 8798.5 8838.3 8868.2 8878.5 8973.0 8992.1 9002.5 9109.5 9126.2 6241.1 6416.8 6834.7 6856.4 7086.5 7473.9 7743.5 7745.3 7747.7 7749.8 6892.6 7059.7 7070.3 7116.4 7202.7 6887.0 6909.4 6912.7 6983.9 6985.7 5758.0 5860.4 5908.1 5986.5 5993.5 4459.3 4547.4 4593.5 4658.0 4738.4 4678.7 4699.1 4710.1 4780.5 4858.8 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#4] prototype.html Cycles:180 Average:4510.66 Median:5198.98 stddev:1328.13 (25.5%) stddev-sans-first:1294.64 18:29:30 INFO - PROCESS | 21384 | Values: 351.1 353.4 355.6 358.7 361.4 5155.3 5164.2 5173.2 5175.5 5213.6 5121.4 5137.5 5175.1 5175.7 5177.8 3758.9 3871.2 4045.2 4051.1 4076.0 4063.0 4065.5 4069.2 4077.9 4088.9 4036.5 4040.6 4044.3 4045.6 4056.0 3825.6 3871.4 3952.0 4040.4 4047.0 3592.4 3603.2 3616.6 3629.4 3630.1 5005.6 5009.8 5031.7 5046.7 5072.5 4306.3 4452.6 4482.4 4679.6 4683.2 5342.4 5349.5 5351.4 5362.9 5367.0 3745.2 3753.7 3762.2 3766.9 3774.3 5452.5 5535.3 5558.3 5570.6 5593.7 3863.2 3872.0 3878.7 3882.2 3889.5 3876.5 3919.8 3920.0 3921.6 3927.0 4388.0 4514.3 4516.3 4532.8 4534.4 5383.5 5387.4 5405.7 5405.8 5414.7 1310.9 1311.4 1317.9 1319.8 1320.8 864.9 905.4 908.4 911.5 912.0 5039.9 5089.6 5162.8 5245.6 5254.2 5531.3 5554.4 5555.5 5557.1 5565.5 5122.8 5243.2 5258.1 5270.5 5279.9 2812.8 2848.4 2850.3 2851.5 2860.8 5525.6 5536.3 5576.3 5582.9 5586.2 5245.8 5501.1 5510.4 5512.8 5523.2 5448.1 5459.0 5469.9 5525.1 5580.6 5301.4 5316.1 5318.8 5342.7 5396.6 5172.3 5500.7 5570.7 5591.9 5592.2 5770.2 5776.6 5779.9 5786.7 5789.9 3737.5 3949.0 3957.5 3965.4 3974.6 5489.6 5511.5 5527.9 5531.1 5533.8 5359.9 5381.6 5392.6 5397.9 5405.9 5145.2 5293.9 5332.9 5347.8 5357.3 5255.9 5267.1 5285.3 5286.2 5287.2 5184.4 5290.6 5291.9 5349.1 5447.3 5286.8 5302.4 5315.2 5340.5 5370.7 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - PROCESS | 21384 | [#5] yui.html Cycles:180 Average:16925.02 Median:27375.46 stddev:22137.50 (80.9%) stddev-sans-first:22165.90 18:29:30 INFO - PROCESS | 21384 | Values: 655.3 748.5 750.1 763.7 784.8 14722.3 16007.5 16164.8 16194.6 16326.3 14537.9 14801.0 14902.4 14961.3 14969.9 789.5 803.6 804.9 805.4 806.6 670.3 699.3 760.4 779.5 786.0 836.7 837.6 837.9 837.9 838.1 831.8 832.4 833.4 833.6 834.8 518.4 534.6 535.3 537.7 589.5 14278.4 14933.3 14941.5 14953.6 14957.9 12690.7 12781.3 12903.0 12909.7 12999.5 10927.1 11125.7 11389.3 11680.0 11737.6 3863.2 3908.4 3927.8 3929.1 3978.5 79514.0 81131.6 83258.6 86468.0 87606.6 9445.2 9449.9 9688.0 9837.8 9847.7 18524.5 18656.4 21335.8 21818.2 22110.7 7542.9 7689.5 7768.7 7972.7 8189.0 4361.1 4383.7 4459.3 4480.1 4510.5 2997.4 3078.2 3081.4 3089.5 3098.8 548.9 555.5 560.9 562.6 570.8 597.4 616.3 639.9 664.9 666.6 8240.9 8422.9 8967.0 9021.0 9083.8 630.9 647.4 692.7 695.5 709.7 4052.4 4097.2 4257.8 4259.9 4309.4 72003.6 73573.0 75395.2 76291.0 76360.6 64981.4 65365.0 65577.7 67142.0 67431.7 57565.7 58172.7 58314.7 59321.9 59472.0 7738.4 8063.2 8259.5 8343.2 8369.5 41573.2 41740.4 42505.6 43200.4 44255.6 43776.0 54202.0 54830.5 55270.7 58307.7 10718.8 12235.7 12241.6 12301.5 12758.5 13222.9 13544.4 14010.6 14212.9 14786.3 11479.4 12251.6 12353.5 12756.3 13212.7 10397.5 12401.8 12569.9 12772.9 12863.2 12182.8 12284.6 12541.2 12631.5 12639.2 10237.7 10557.2 10832.6 10914.3 11431.3 9801.4 9814.3 9908.3 9936.4 9945.8 18:29:30 INFO - PROCESS | 21384 | -------- Summary: end -------- 18:29:30 INFO - PROCESS | 21384 | 18:29:30 INFO - TEST-INFO | 21384: exit 0 18:29:30 INFO - TEST-OK | dromaeo_css | took 1117700ms 18:29:30 INFO - TEST-START | kraken 18:29:30 INFO - operating with platform_type : linux_ 18:29:30 INFO - Initialising browser for kraken test... 18:29:30 INFO - TEST-INFO | started process 21696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfbmQr7/profile http://localhost:59271/getInfo.html) 18:29:35 INFO - PROCESS | 21696 | __metrics Screen width/height:1600/1200 18:29:35 INFO - PROCESS | 21696 | colorDepth:24 18:29:35 INFO - PROCESS | 21696 | Browser inner width/height: 1024/697 18:29:35 INFO - PROCESS | 21696 | __metrics 18:29:35 INFO - PROCESS | 21696 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:29:35 INFO - PROCESS | 21696 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:29:36 INFO - PROCESS | 21696 | console.error: PushService: 18:29:36 INFO - PROCESS | 21696 | stateChangeProcessEnqueue: Error transitioning state 18:29:36 INFO - PROCESS | 21696 | UnknownError 18:29:37 INFO - TEST-INFO | 21696: exit 0 18:29:37 INFO - Browser initialized. 18:29:37 INFO - Running cycle 1/1 for kraken test... 18:29:37 INFO - TEST-INFO | started process 21779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfbmQr7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:49 INFO - PROCESS | 21779 | RSS: Main: 173187072 18:29:49 INFO - PROCESS | 21779 | 18:29:51 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59271/tests/kraken/driver.html?testName=audio-beat-detection) 18:29:52 INFO - PROCESS | 21779 | RSS: Main: 234901504 18:29:52 INFO - PROCESS | 21779 | 18:29:55 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59271/tests/kraken/driver.html?testName=audio-dft) 18:29:55 INFO - PROCESS | 21779 | RSS: Main: 216784896 18:29:55 INFO - PROCESS | 21779 | 18:29:58 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59271/tests/kraken/driver.html?testName=audio-fft) 18:29:59 INFO - PROCESS | 21779 | RSS: Main: 299450368 18:29:59 INFO - PROCESS | 21779 | 18:30:01 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59271/tests/kraken/driver.html?testName=audio-oscillator) 18:30:02 INFO - PROCESS | 21779 | RSS: Main: 209674240 18:30:02 INFO - PROCESS | 21779 | 18:30:04 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:30:05 INFO - PROCESS | 21779 | RSS: Main: 214683648 18:30:05 INFO - PROCESS | 21779 | 18:30:08 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59271/tests/kraken/driver.html?testName=imaging-darkroom) 18:30:08 INFO - PROCESS | 21779 | RSS: Main: 253415424 18:30:08 INFO - PROCESS | 21779 | 18:30:12 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59271/tests/kraken/driver.html?testName=imaging-desaturate) 18:30:12 INFO - PROCESS | 21779 | RSS: Main: 253157376 18:30:12 INFO - PROCESS | 21779 | 18:30:15 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59271/tests/kraken/driver.html?testName=json-parse-financial) 18:30:16 INFO - PROCESS | 21779 | RSS: Main: 252211200 18:30:16 INFO - PROCESS | 21779 | 18:30:17 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:30:18 INFO - PROCESS | 21779 | RSS: Main: 213884928 18:30:18 INFO - PROCESS | 21779 | 18:30:20 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:30:20 INFO - PROCESS | 21779 | RSS: Main: 221085696 18:30:20 INFO - PROCESS | 21779 | 18:30:22 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:30:23 INFO - PROCESS | 21779 | RSS: Main: 230502400 18:30:23 INFO - PROCESS | 21779 | 18:30:26 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:30:26 INFO - PROCESS | 21779 | RSS: Main: 239894528 18:30:26 INFO - PROCESS | 21779 | 18:30:29 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:30:30 INFO - PROCESS | 21779 | RSS: Main: 236572672 18:30:30 INFO - PROCESS | 21779 | 18:30:31 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:59271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59271/tests/kraken/driver.html?testName=ai-astar) 18:30:32 INFO - PROCESS | 21779 | RSS: Main: 286429184 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | __start_tp_report 18:30:32 INFO - PROCESS | 21779 | _x_x_mozilla_page_load 18:30:32 INFO - PROCESS | 21779 | _x_x_mozilla_page_load_details 18:30:32 INFO - PROCESS | 21779 | |i|pagename|runs| 18:30:32 INFO - PROCESS | 21779 | |0;ai-astar;112;116;113;113;113;113;115;112;113;114 18:30:32 INFO - PROCESS | 21779 | |1;audio-beat-detection;150;122;124;164;124;125;136;125;143;129 18:30:32 INFO - PROCESS | 21779 | |2;audio-dft;161;161;160;160;161;163;162;163;160;161 18:30:32 INFO - PROCESS | 21779 | |3;audio-fft;77;76;78;107;76;76;76;77;95;77 18:30:32 INFO - PROCESS | 21779 | |4;audio-oscillator;85;84;111;84;84;84;110;84;83;108 18:30:32 INFO - PROCESS | 21779 | |5;imaging-gaussian-blur;128;128;127;128;127;127;127;127;127;127 18:30:32 INFO - PROCESS | 21779 | |6;imaging-darkroom;145;146;145;145;146;145;146;146;145;146 18:30:32 INFO - PROCESS | 21779 | |7;imaging-desaturate;95;95;95;95;96;95;95;96;95;95 18:30:32 INFO - PROCESS | 21779 | |8;json-parse-financial;54;51;51;52;52;53;54;54;53;54 18:30:32 INFO - PROCESS | 21779 | |9;json-stringify-tinderbox;65;65;65;65;65;65;65;65;65;66 18:30:32 INFO - PROCESS | 21779 | |10;stanford-crypto-aes;80;80;82;82;82;95;82;82;80;82 18:30:32 INFO - PROCESS | 21779 | |11;stanford-crypto-ccm;150;133;137;136;137;133;134;133;180;152 18:30:32 INFO - PROCESS | 21779 | |12;stanford-crypto-pbkdf2;174;171;172;173;171;170;169;173;168;167 18:30:32 INFO - PROCESS | 21779 | |13;stanford-crypto-sha256-iterative;66;64;64;65;69;71;65;64;67;64 18:30:32 INFO - PROCESS | 21779 | __end_tp_report 18:30:32 INFO - PROCESS | 21779 | __start_cc_report 18:30:32 INFO - PROCESS | 21779 | _x_x_mozilla_cycle_collect,1671 18:30:32 INFO - PROCESS | 21779 | __end_cc_report 18:30:32 INFO - PROCESS | 21779 | __startTimestamp1481769032161__endTimestamp 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | ------- Summary: start ------- 18:30:32 INFO - PROCESS | 21779 | Number of tests: 14 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:1.26 (1.1%) stddev-sans-first:1.24 18:30:32 INFO - PROCESS | 21779 | Values: 112.0 116.0 113.0 113.0 113.0 113.0 115.0 112.0 113.0 114.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:132.50 stddev:14.03 (10.6%) stddev-sans-first:13.67 18:30:32 INFO - PROCESS | 21779 | Values: 150.0 122.0 124.0 164.0 124.0 125.0 136.0 125.0 143.0 129.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 18:30:32 INFO - PROCESS | 21779 | Values: 161.0 161.0 160.0 160.0 161.0 163.0 162.0 163.0 160.0 161.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#3] audio-fft Cycles:10 Average:81.50 Median:77.00 stddev:10.68 (13.9%) stddev-sans-first:11.20 18:30:32 INFO - PROCESS | 21779 | Values: 77.0 76.0 78.0 107.0 76.0 76.0 76.0 77.0 95.0 77.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:84.00 stddev:12.43 (14.8%) stddev-sans-first:12.94 18:30:32 INFO - PROCESS | 21779 | Values: 85.0 84.0 111.0 84.0 84.0 84.0 110.0 84.0 83.0 108.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#5] imaging-gaussian-blur Cycles:10 Average:127.30 Median:127.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:30:32 INFO - PROCESS | 21779 | Values: 128.0 128.0 127.0 128.0 127.0 127.0 127.0 127.0 127.0 127.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 18:30:32 INFO - PROCESS | 21779 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:30:32 INFO - PROCESS | 21779 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.50 stddev:1.23 (2.3%) stddev-sans-first:1.22 18:30:32 INFO - PROCESS | 21779 | Values: 54.0 51.0 51.0 52.0 52.0 53.0 54.0 54.0 53.0 54.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 18:30:32 INFO - PROCESS | 21779 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 66.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.00 stddev:4.42 (5.4%) stddev-sans-first:4.58 18:30:32 INFO - PROCESS | 21779 | Values: 80.0 80.0 82.0 82.0 82.0 95.0 82.0 82.0 80.0 82.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:137.00 stddev:14.90 (10.9%) stddev-sans-first:15.56 18:30:32 INFO - PROCESS | 21779 | Values: 150.0 133.0 137.0 136.0 137.0 133.0 134.0 133.0 180.0 152.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:171.50 stddev:2.30 (1.3%) stddev-sans-first:2.13 18:30:32 INFO - PROCESS | 21779 | Values: 174.0 171.0 172.0 173.0 171.0 170.0 169.0 173.0 168.0 167.0 18:30:32 INFO - PROCESS | 21779 | 18:30:32 INFO - PROCESS | 21779 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.50 stddev:2.42 (3.7%) stddev-sans-first:2.57 18:30:32 INFO - PROCESS | 21779 | Values: 66.0 64.0 64.0 65.0 69.0 71.0 65.0 64.0 67.0 64.0 18:30:32 INFO - PROCESS | 21779 | -------- Summary: end -------- 18:30:32 INFO - PROCESS | 21779 | 18:30:33 INFO - TEST-INFO | 21779: exit 0 18:30:33 INFO - TEST-OK | kraken | took 62541ms 18:30:33 INFO - SUITE-END | took 1180s 18:30:33 INFO - Completed test suite (00:19:40) 18:30:33 INFO - javascript benchmark 18:30:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3023.4406634772567, 3065.371598822241, 3067.18719539322, 3092.350606372662, 3113.517404802255, 5171.224316352549, 5227.73861306935, 5265.052290587692, 5295.099833028385, 5322.733863306834, 5243.370795504536, 5275.657082289648, 5308.283381743464, 5319.893602127955, 5353.197020446935, 4735.668503204777, 4765.785539650707, 4784.66507344486, 4832.154372984731, 4836.564709176174, 4896.926546101811, 4904.36243288372, 4951.876203094934, 4955.0, 5000.249962505635, 4732.006278681447, 4837.975810120962, 4838.274258861164, 4848.296996935424, 4942.604591632661, 4753.47711751707, 4832.951670483285, 4838.201696720037, 4848.0, 4851.345068415754, 4794.424669039703, 4804.639652026112, 4805.231163013936, 4810.951890481086, 4813.735244561548, 5171.146760784466, 5195.142801437758, 5215.269862219292, 5217.139172036647, 5221.477852214786, 5599.32808063035, 5599.552035837164, 5604.859878503029, 5612.41069687681, 5655.123455864347, 5650.971745141248, 5652.208690783294, 5653.293338332708, 5657.915131273035, 5658.122990936411, 5182.53357197854, 5235.947640523622, 5248.05535003703, 5288.68267903927, 5329.467053294639, 5669.886602268014, 5773.740181691834, 5777.451142141574, 5787.913181302284, 5824.417558244227, 5562.9443705563235, 5568.0, 5571.13647384656, 5572.331320241516, 5573.331200255995, 5517.641353312022, 5544.22380866679, 5554.972225138848, 5642.0, 5642.7178641067785, 5600.747966341443, 5611.130274807411, 5612.691301978431, 5613.7193140342815, 5638.774449021993, 5506.449355064461, 5520.944790552124, 5551.167324901297, 5649.745761440745, 5660.377358490563, 4285.078708077778, 4320.913581728348, 4330.523642399334, 4334.263175260214, 4340.565943405697, 3849.5188101487315, 3899.2981263372867, 3917.118648304109, 3936.2127574485216, 3940.0, 5282.8943421132135, 5335.9466405336225, 5338.332708411449, 5338.412774594792, 5341.732913354317, 5635.971820140955, 5658.066419040894, 5661.433856614388, 5661.603687741901, 5664.320281566238, 5242.7378631068295, 5335.1730481775385, 5339.652922560099, 5343.091674415358, 5344.038073146793, 4848.5636292733825, 4849.878753031202, 4858.514148585184, 4860.73265970375, 4863.683860549053, 5738.024535828919, 5758.452946970031, 5759.942400576025, 5762.078067509262, 5763.596548241667, 5563.109902415756, 5590.189422533791, 5623.606347555632, 5640.774369025193, 5701.885962280814, 5743.741531631003, 5748.655080695172, 5752.511036561772, 5757.453041961007, 5759.049756790083, 5374.140137577969, 5542.695151766691, 5553.444655534414, 5555.611107222456, 5565.0817615094675, 5489.066858634122, 5583.218349431002, 5628.802991895264, 5629.071203251581, 5747.482726554463, 5555.749991250323, 5559.110542313372, 5569.053260945568, 5573.303337082864, 5574.470425309589, 5485.6982865942755, 5525.723713814374, 5529.612927095065, 5534.0, 5550.639208451437, 5352.170413585901, 5375.650582712111, 5415.729213539228, 5419.05166595852, 5423.945760542344, 5586.245856809279, 5586.553075754011, 5587.357453892854, 5590.692511911884, 5616.691081990366, 5453.618246722692, 5460.726963651881, 5461.322277809979, 5465.125579907196, 5469.097598896137], "value": 5200.773913580007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.5009616105102, 552.4447929830521, 553.6401339129562, 567.5346216102797, 569.3822202909844, 31528.684713152863, 39450.0, 40937.3859392109, 41231.17537649245, 44590.7770461148, 42764.572354276526, 43068.353974690406, 43180.78409607955, 43198.0, 43214.7839260804, 7841.137474877759, 8587.484750914966, 8750.781230469225, 8754.6935857245, 8759.999999999967, 7702.8844567331535, 10129.088382045615, 10368.11870990963, 10369.014943580385, 10657.200709946746, 10415.218858585598, 10471.84292235617, 10479.05688488036, 10480.633177838778, 10487.370757754521, 10255.641052563162, 10321.690349289533, 10359.430231337274, 10360.378377297311, 10372.11836993849, 10231.13035391985, 10305.948470257676, 10308.0, 10308.690739277834, 10322.329048611817, 39914.80042599768, 40396.0, 40406.19187616261, 40407.595924040674, 40483.7975810122, 3212.646608873022, 3222.7905186162825, 3256.3812875553567, 3386.8137252451106, 3512.929741405183, 30224.24439389011, 30345.241368965733, 30379.848100759355, 30406.391872162432, 30833.0, 4069.3442403775107, 4096.754194748325, 4116.835326586933, 4182.958170418287, 4242.851500197509, 324165.0, 324433.0, 324780.0, 326090.9999999976, 326343.0, 55367.0, 55379.72310138504, 55407.722961384934, 55573.0, 55596.16605750917, 58164.0, 58217.41782582205, 58265.417345826, 58324.12513812296, 58373.70813145992, 12968.416421261067, 13291.401886914919, 13302.26837523926, 13305.534306299232, 13318.400671969785, 9502.382345147544, 9692.127708506268, 10798.730031749112, 10898.836517452244, 11175.32948023105, 15988.120653364176, 16013.519594412186, 16021.59946001336, 16083.437079702155, 16091.597710057342, 5745.971270143623, 5840.766369345263, 5868.589198756132, 5872.706364681749, 5888.705564721747, 882.5940067568862, 948.3314263444394, 1063.6649455421518, 1064.9414282214398, 1094.2777766673958, 28419.28951776181, 29949.700502994692, 30068.849655751583, 30298.091057267877, 30467.0, 1019.3221507697298, 1025.6153942271649, 1056.6671498478108, 1073.8765042020268, 1099.9230053896151, 15188.924055379874, 15811.288492017657, 15908.0, 15998.280077396545, 16151.0, 426064.0, 428926.0, 431624.0, 432436.0, 432533.0, 55059.72470137704, 55111.44888551063, 55153.72423137858, 55207.44792552103, 55330.17004744932, 1099.9780004399868, 1134.466800603715, 1227.2084505494086, 1249.450241893564, 1277.2400421749044, 27172.32069198286, 30483.390332193674, 30640.84679576677, 30827.537586935767, 31212.843935780176, 46931.53068469271, 47751.0, 47769.52230477651, 47835.28247076227, 47861.76069119632, 357274.0, 359397.0, 359621.0, 359945.0, 360609.0, 24594.0, 24828.627570586817, 25261.326654568962, 25808.741912580634, 39077.6092239074, 40346.0, 40633.84548154082, 40838.59161408348, 41179.38230926479, 41678.79160604178, 34974.0, 35065.824670876485, 35515.0, 35573.82213088918, 35740.10649733777, 34912.0, 35551.11122221965, 35619.0, 35707.82146089253, 35771.28457430889, 33853.15367115842, 34291.4856277161, 34350.48474272938, 35084.64915350814, 35087.47368789521, 29896.850515747283, 32356.514652279762, 32363.67636323701, 32366.352672945937, 32593.34813303768, 24498.21759731174, 24657.137000204908, 24694.382640434134, 24760.0, 24927.501449971263], "value": 18679.61490302961, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.7220512410567, 378.2265267527907, 379.00585375533274, 384.77875221747496, 409.5699515508715, 14568.0, 15003.324850381732, 17715.202815873283, 18501.0, 18541.907290463547, 16590.66818663626, 16620.418285360014, 16863.91568042161, 16949.237284322204, 16971.860205861925, 3685.981570092146, 5426.538744206738, 5663.830085097454, 5682.26130603021, 5687.118496633028, 3687.1703866630064, 5908.61593996391, 6145.723442445078, 6176.938230617681, 6280.371962803729, 6041.395860413945, 6230.376962303779, 6238.03310486873, 6238.407351301619, 6240.937590624104, 6211.844703882416, 6231.688415579203, 6249.031400132987, 6249.812505624793, 6259.906101408483, 5332.946670533283, 5456.072467680504, 5494.2582751328855, 5499.422560631157, 5533.08704063829, 21673.783262167493, 21699.891500542555, 21774.237901673365, 22012.239020878453, 22191.66712499314, 2083.7186979757844, 2310.5263420998654, 2327.976720232793, 2505.8120640952, 2597.0261152067974, 9150.267978561698, 9179.586918588613, 9324.8601270981, 9410.247180225633, 9511.857322140173, 1806.1872157529033, 1857.8142185781448, 1928.6528424883516, 1935.5354714868472, 1989.055198780582, 73422.63288683523, 77519.0, 80403.5979820103, 81633.0, 81799.5910020458, 10464.790704186025, 10529.631462898915, 10533.157347412189, 10719.0, 10934.179936504794, 14790.408383664748, 14800.0, 14810.185439800698, 14828.481003164838, 14832.18422986746, 6895.379585797334, 7106.431485481149, 7234.204237533867, 7330.560166390033, 7342.632868356561, 6647.867042659216, 6665.833354166088, 6837.316268373223, 6886.586804791728, 6891.931080689229, 4163.354680024601, 4220.430241917364, 4419.558044195619, 4623.768811559408, 4679.040796636681, 1854.7403363529118, 1884.7361369408293, 1888.1408958923657, 1893.2994791927076, 1903.7620297462818, 787.9606019698992, 863.96976105836, 867.6919693508839, 879.9912000880038, 973.3089506450358, 17009.319627214772, 18236.346732512964, 18585.5353616158, 18693.345732899284, 19510.0, 892.2772554231094, 952.547190983221, 957.698325027628, 993.6621548673486, 1042.1818872185297, 10016.248781341428, 10180.440075795754, 10374.844377334346, 10489.108425783741, 10496.475176241158, 389086.0, 390057.0302469336, 397501.0, 397995.0, 400927.0, 82282.0, 82358.0, 85114.14885851186, 86264.0, 88324.0, 952.3857112162755, 984.1880448629909, 994.4878387630376, 1002.0330381182195, 1010.4846528270488, 15900.68198636044, 16215.513534593982, 16688.666226675643, 16729.33082676728, 17064.65870682604, 39242.607573923895, 39285.80357098196, 39439.408408874464, 39998.2000359997, 41286.38070428886, 53350.0, 53817.46182538281, 53854.73072634612, 55263.0, 56367.71816140893, 7495.43784216175, 7655.311022008046, 7765.844683106419, 7854.546908821886, 7996.24035716606, 9244.491552964382, 11918.582849600223, 12010.879891201326, 12131.878681213428, 12139.575114870937, 10420.426876521864, 10601.151907847508, 10836.945815270874, 11056.281341713086, 11294.94352528232, 9891.159251463418, 10193.337433066827, 10327.0, 10357.585696572052, 10968.396738179477, 10799.892001079888, 10857.34855908648, 10880.183986201226, 10897.237193396386, 11341.603043893425, 7603.581803000888, 7638.656260468181, 7730.845383092419, 7916.802079947817, 7943.44395892282, 10218.335808172445, 10856.294340867818, 10858.0, 10884.12926965825, 11264.831027534758], "value": 8656.429035750096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.8858902698755, 1366.6105160029392, 1387.1330418488444, 1489.4116823854579, 1704.4971733338657, 6900.482463815215, 7444.478886477942, 7740.806479838006, 7767.805804854867, 7792.181820908808, 7640.961795191016, 7759.340456061227, 7772.067351917778, 7953.920460795391, 7962.243586859254, 1845.815418458157, 1900.9714854277197, 2073.2536286936693, 2118.1739121742535, 2127.723395958531, 2053.2505635443104, 2110.8205802506805, 2115.5134319106537, 2125.224293133003, 2131.5523740014537, 2084.864483808555, 2100.4433825036376, 2101.695254188142, 2108.399106254718, 2116.703661487393, 2100.065470865458, 2122.7983341582526, 2123.1719629344643, 2123.936281911545, 2125.08621292844, 2079.0852025109057, 2121.1621409543145, 2121.289368061701, 2125.6811478278228, 2125.691774692661, 7673.577953212571, 7742.574158421285, 7845.0585929688805, 7890.408219383569, 7926.722564710265, 4607.608353289975, 4724.503927087675, 4726.4328280606205, 4734.928976065362, 4743.24108142699, 3038.2100653829943, 3199.104250809777, 3199.7120259176786, 3201.983990080058, 3207.8075315481146, 993.2897977945803, 1000.2148313573882, 1028.8611037509916, 1043.9112675422598, 1044.5038606661851, 10468.79062418755, 10781.676549703521, 10795.406252656101, 10803.297785643908, 10807.837882431691, 3901.356276214393, 4018.9196216076098, 4126.133511962462, 4194.643455306272, 4201.222773786859, 9132.3150763693, 9141.451512909247, 9148.634054637872, 9149.268058555299, 9151.35940484173, 4717.764111794397, 4747.881302967385, 4748.026654535812, 4753.786079626425, 4755.096531658974, 3954.525456945185, 3992.5608183099835, 4377.9781101094295, 4411.0, 4412.227860124465, 3518.3666939950544, 3615.403458429382, 3656.7805931643657, 3677.3013127505687, 3695.667389934919, 1142.7485953090306, 1171.9941400293114, 1182.2079206931376, 1182.9704257393462, 1195.1096433157354, 760.5436737957182, 905.9264771246094, 908.3505293715026, 933.4306073295285, 934.4486752815872, 5245.711485868237, 5284.101702710548, 5345.572354211654, 5455.699936503451, 5479.863003424946, 393.6004954970707, 398.1559094719156, 399.6023956163578, 401.70675406952506, 404.0283119098573, 3645.927081458356, 3703.3889408247337, 3767.472553842464, 3778.7732736035928, 3873.4577159197734, 9224.584893679801, 9466.621335146654, 9688.70933871985, 9723.367981081206, 9744.269179811543, 9634.373765705206, 9638.566264517973, 9643.035696430441, 9645.938946715856, 9656.565454554562, 6978.337057979484, 7204.891926621105, 7258.129024517092, 7263.3826124778925, 7374.410047196318, 5349.759260833194, 5418.891622167614, 5513.283273174462, 5611.551075913999, 5694.0, 8745.207232517447, 8798.516081615573, 8838.337124715801, 8868.246199073152, 8878.51168185756, 8972.955135224543, 8992.100789921216, 9002.459852408876, 9109.498977556297, 9126.178643921814, 6241.063840423973, 6416.839579010375, 6834.692438840165, 6856.417204537471, 7086.50394472382, 7473.8878916817375, 7743.5353878767455, 7745.341645960157, 7747.72882949094, 7749.845003100019, 6892.586444813327, 7059.71761129549, 7070.257622949516, 7116.430685545248, 7202.675879585536, 6887.0, 6909.412699920362, 6912.723491060301, 6983.860322793617, 6985.685644145924, 5757.97121014409, 5860.38465961085, 5908.084246941731, 5986.491148252448, 5993.490553303018, 4459.2876903521155, 4547.3633691282585, 4593.494715581351, 4658.0, 4738.431388233434, 4678.719276843264, 4699.130660827758, 4710.105080034782, 4780.474147843804, 4858.8299409521915], "value": 4078.4003309499294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.1116874308001, 353.3586540429121, 355.59284619111116, 358.7452908435011, 361.400075874049, 5155.252488389181, 5164.199549069891, 5173.172292433207, 5175.508326708966, 5213.556847667953, 5121.359830021247, 5137.537621614054, 5175.1461008933575, 5175.663581867176, 5177.818776342829, 3758.868439604614, 3871.2451072040963, 4045.1707399982934, 4051.149258655686, 4075.9592404075875, 4062.9593704063022, 4065.4714887064642, 4069.2471892699946, 4077.8776636700945, 4088.897777555555, 4036.495438070241, 4040.5757395473493, 4044.3326851069537, 4045.59544045596, 4055.9797201013953, 3825.61743825618, 3871.380579107329, 3951.9802400987955, 4040.393940908858, 4047.0084829216735, 3592.4252119660728, 3603.1532589841368, 3616.566012078541, 3629.364861149288, 3630.146915474861, 5005.599552035828, 5009.84773502093, 5031.748412579356, 5046.7224302663335, 5072.51811077947, 4306.332518459643, 4452.621527170194, 4482.394876691637, 4679.649026323005, 4683.180443422421, 5342.4322204514965, 5349.528879558114, 5351.411344752074, 5362.91955620666, 5366.973165134188, 3745.232227393405, 3753.680937120348, 3762.2287431076566, 3766.9075967969256, 3774.2640185163905, 5452.454754524555, 5535.308086489189, 5558.333000039981, 5570.635194377403, 5593.720313984284, 3863.227354529049, 3871.9612803872164, 3878.7284890057426, 3882.2235552889533, 3889.463661853552, 3876.5348158221195, 3919.784411857318, 3919.981209677019, 3921.6274453927404, 3927.0, 4388.0, 4514.322851572225, 4516.254817955, 4532.750698711537, 4534.4129554656265, 5383.461653834585, 5387.4073851876265, 5405.675609475704, 5405.8107966221, 5414.675119492843, 1310.8885744711615, 1311.4459365651733, 1317.8879795217515, 1319.8284223050941, 1320.80187971805, 864.8659457784054, 905.4160066756905, 908.4231512989168, 911.4667919267303, 911.9908800912038, 5039.899202016012, 5089.600359900983, 5162.845114646567, 5245.606579506552, 5254.15933450646, 5531.280933478622, 5554.361248456398, 5555.472230138131, 5557.138643510262, 5565.465929135028, 5122.820701275437, 5243.23973023917, 5258.132408152688, 5270.5256526912, 5279.947200527946, 2812.831230126199, 2848.4018356145443, 2850.2589326774773, 2851.458222937635, 2860.8140470869325, 5525.64621667694, 5536.335639723259, 5576.302962129734, 5582.91625625616, 5586.245856809279, 5245.816396426107, 5501.092319767274, 5510.448955104457, 5512.751926163252, 5523.1991361253195, 5448.128299472065, 5459.00746227632, 5469.917951230735, 5525.0883604204855, 5580.553555715533, 5301.448029819952, 5316.102724015498, 5318.8458674237, 5342.717747740437, 5396.622236443412, 5172.327597412313, 5500.67983683921, 5570.749316280858, 5591.916121258266, 5592.189132575672, 5770.221020162392, 5776.595638305278, 5779.8555036124435, 5786.681732504752, 5789.884202316014, 3737.5141231639714, 3948.9605103948593, 3957.4855268816023, 3965.425013372987, 3974.602539746002, 5489.64317319373, 5511.53151982086, 5527.8894422112135, 5531.115021596525, 5533.806316778893, 5359.866003349947, 5381.555804285339, 5392.649477783932, 5397.892042159214, 5405.8918821622565, 5145.202493613496, 5293.920591191212, 5332.920315958372, 5347.839564813062, 5357.330333708286, 5255.947440525546, 5267.104592219255, 5285.260063591023, 5286.20706893969, 5287.180487024518, 5184.403793563638, 5290.619221350331, 5291.9470805292995, 5349.144136938071, 5447.319085114361, 5286.810467644749, 5302.416734159317, 5315.176147697113, 5340.477963780304, 5370.677759334452], "value": 4094.0279120228597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.285738544986, 748.5321673953781, 750.1298493747254, 763.7023737096446, 784.8391079828635, 14722.337494812733, 16007.519774406772, 16164.83835161648, 16194.595135121628, 16326.265318060687, 14537.85462145381, 14801.037932534404, 14902.40390384386, 14961.251937403113, 14969.850301496981, 789.4592204340016, 803.618281316373, 804.9436539442212, 805.4080251015499, 806.5725165662209, 670.2627110178813, 699.3076853914639, 760.3992845651928, 779.4504874063772, 786.0096278688836, 836.6695155413609, 837.5519097282955, 837.8701301298308, 837.9287760540361, 838.1074156023815, 831.8170002599419, 832.4131487301438, 833.4374297349265, 833.62903507939, 834.7620928035511, 518.3624142304986, 534.5910378560404, 535.253321616343, 537.720385399594, 589.4900910712203, 14278.428862845474, 14933.253337333199, 14941.477048303257, 14953.626159345995, 14957.850421495863, 12690.682732931751, 12781.297028663419, 12903.0322725795, 12909.67725806853, 12999.545015924397, 10927.125829933666, 11125.666230013112, 11389.316641001566, 11680.0, 11737.647870563897, 3863.2466668999555, 3908.4332771748223, 3927.7643341399607, 3929.135590170158, 3978.4827972363696, 79514.0, 81131.5943420285, 83258.58370708168, 86468.0, 87606.5619671904, 9445.244380449685, 9449.858252126223, 9688.0, 9837.803243935225, 9847.704568862946, 18524.536886577942, 18656.440306790817, 21335.78664213369, 21818.236361727264, 22110.668339974913, 7542.886856697154, 7689.466170142632, 7768.728094516778, 7972.681092756341, 8188.959055204686, 4361.149575832682, 4383.693141480066, 4459.331100334975, 4480.14877173336, 4510.481294651157, 2997.355568552728, 3078.184281165493, 3081.383723255358, 3089.5211242257487, 3098.7830851840604, 548.9149181876816, 555.5416781155562, 560.9018421776173, 562.5780664501624, 570.823044856096, 597.4413922982026, 616.3189675408609, 639.9360063993656, 664.8736740019381, 666.5867162359368, 8240.909658877921, 8422.915770842214, 8966.955165224132, 9021.0, 9083.81832363349, 630.8549033722178, 647.3526473526474, 692.7159864455549, 695.5011949249375, 709.6664567653195, 4052.3516237402464, 4097.160082183145, 4257.808398622069, 4259.936100958488, 4309.353596960481, 72003.6399818008, 73573.0, 75395.24604753991, 76291.0, 76360.61819690866, 64981.35018649847, 65365.0, 65577.67211163914, 67142.0, 67431.66284168548, 57565.71217143888, 58172.70913645405, 58314.7084264576, 59321.88137423451, 59472.0, 7738.41961852852, 8063.153368896416, 8259.463134896212, 8343.249107580346, 8369.49783013021, 41573.1685366297, 41740.373894391, 42505.57494425016, 43200.35199472073, 44255.55744442515, 43776.0, 54202.0, 54830.45169548413, 55270.72364638151, 58307.70846145742, 10718.785624287626, 12235.69410764738, 12241.632751017483, 12301.507939682671, 12758.48966041348, 13222.918798620854, 13544.390502427612, 14010.579682609537, 14212.857871421153, 14786.334614942138, 11479.36863472517, 12251.571195008131, 12353.505859765508, 12756.298403587893, 13212.735745285232, 10397.465722912499, 12401.813972790596, 12569.874301257236, 12772.936135319263, 12863.163894346837, 12182.817257741319, 12284.570040048555, 12541.184822986841, 12631.494740210288, 12639.241645501299, 10237.692869213935, 10557.20820938448, 10832.566697332019, 10914.345139291669, 11431.314121152758, 9801.411915285105, 9814.263930205407, 9908.306418550632, 9936.354136981074, 9945.751356216153], "value": 6803.450866789324, "unit": "score"}], "name": "dromaeo_css", "value": 6761.32920462646, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 113.0, 113.0, 113.0, 113.0, 115.0, 112.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 124.0, 164.0, 124.0, 125.0, 136.0, 125.0, 143.0, 129.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 161.0, 163.0, 162.0, 163.0, 160.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 78.0, 107.0, 76.0, 76.0, 76.0, 77.0, 95.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 111.0, 84.0, 84.0, 84.0, 110.0, 84.0, 83.0, 108.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 127.0, 128.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0], "value": 127.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 52.0, 52.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 82.0, 82.0, 82.0, 95.0, 82.0, 82.0, 80.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 133.0, 137.0, 136.0, 137.0, 133.0, 134.0, 133.0, 180.0, 152.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 172.0, 173.0, 171.0, 170.0, 169.0, 173.0, 168.0, 167.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 65.0, 69.0, 71.0, 65.0, 64.0, 67.0, 64.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1529.8, "alertThreshold": 2.0}]} 18:30:33 INFO - Return code: 0 18:30:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:30:33 INFO - # TBPL SUCCESS # 18:30:33 INFO - Running post-action listener: _resource_record_post_action 18:30:33 INFO - [mozharness: 2016-12-15 02:30:33.943344Z] Finished run-tests step (success) 18:30:33 INFO - Running post-run listener: _resource_record_post_run 18:30:34 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392949760; Read time: 8; Write time: 288340 18:30:34 INFO - TinderboxPrint: CPU usage
13.1% 18:30:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:30:34 INFO - TinderboxPrint: I/O write bytes / time
392,949,760 / 288,340 18:30:34 INFO - TinderboxPrint: CPU idle
8,303.3 (86.9%) 18:30:34 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 18:30:34 INFO - TinderboxPrint: Swap in / out
0 / 0 18:30:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103923712; Read time: 0; Write time: 82972 18:30:34 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 289026048; Read time: 0; Write time: 205368 18:30:34 INFO - Running post-run listener: _upload_blobber_files 18:30:34 INFO - Blob upload gear active. 18:30:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:30:34 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 18:30:34 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'] 18:30:34 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 18:30:34 INFO - (blobuploader) - INFO - Open directory for files ... 18:30:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:30:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:30:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:35 INFO - (blobuploader) - INFO - Done attempting. 18:30:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:30:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:39 INFO - (blobuploader) - INFO - Done attempting. 18:30:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:30:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:39 INFO - (blobuploader) - INFO - Done attempting. 18:30:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:30:39 INFO - Return code: 0 18:30:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:30:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:30:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2de334f27bef6cd618ef436ef0d67cfe0494e38795a7f96f6bc71ed55d1c41b05cfbbd4ee693b36eee8d58d44fabf18628904e5edf955a2a970e6c68ae86ddc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6abe95fb120c32112703c5a36ba9f934c4008cfefe7ec883574785c1776a6b96ca1b95f0ab0ff62d9dc1235d7b8fa962be8c55314dffb50659baa6689fa854a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e896f4b9e04fb7dc33ef8b2ad0862279e8fea648a0b62da8ae8aa69eff3d1e34187db11f883f5db9af4be06008fdbc24caaa74cb9f2837440b0165538a17edf"} 18:30:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:30:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:30:39 INFO - Contents: 18:30:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2de334f27bef6cd618ef436ef0d67cfe0494e38795a7f96f6bc71ed55d1c41b05cfbbd4ee693b36eee8d58d44fabf18628904e5edf955a2a970e6c68ae86ddc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6abe95fb120c32112703c5a36ba9f934c4008cfefe7ec883574785c1776a6b96ca1b95f0ab0ff62d9dc1235d7b8fa962be8c55314dffb50659baa6689fa854a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e896f4b9e04fb7dc33ef8b2ad0862279e8fea648a0b62da8ae8aa69eff3d1e34187db11f883f5db9af4be06008fdbc24caaa74cb9f2837440b0165538a17edf"} 18:30:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:30:39 INFO - Copying logs to upload dir... 18:30:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:30:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.084578 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-14 18:30:39.642917) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 18:30:39.645924) ========= 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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2de334f27bef6cd618ef436ef0d67cfe0494e38795a7f96f6bc71ed55d1c41b05cfbbd4ee693b36eee8d58d44fabf18628904e5edf955a2a970e6c68ae86ddc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6abe95fb120c32112703c5a36ba9f934c4008cfefe7ec883574785c1776a6b96ca1b95f0ab0ff62d9dc1235d7b8fa962be8c55314dffb50659baa6689fa854a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e896f4b9e04fb7dc33ef8b2ad0862279e8fea648a0b62da8ae8aa69eff3d1e34187db11f883f5db9af4be06008fdbc24caaa74cb9f2837440b0165538a17edf"} build_url:https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011599 build_url: 'https://queue.taskcluster.net/v1/task/ZTlkROY5QcCxa1NKDxLMSw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2de334f27bef6cd618ef436ef0d67cfe0494e38795a7f96f6bc71ed55d1c41b05cfbbd4ee693b36eee8d58d44fabf18628904e5edf955a2a970e6c68ae86ddc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6abe95fb120c32112703c5a36ba9f934c4008cfefe7ec883574785c1776a6b96ca1b95f0ab0ff62d9dc1235d7b8fa962be8c55314dffb50659baa6689fa854a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e896f4b9e04fb7dc33ef8b2ad0862279e8fea648a0b62da8ae8aa69eff3d1e34187db11f883f5db9af4be06008fdbc24caaa74cb9f2837440b0165538a17edf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 18:30:39.674297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:30:39.674678) ========= 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=cbe409c6de289db6ac707de3000001d4-1481766085.542955-230696510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.29 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 18:30:39.974029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 18:30:39.974375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 18:30:39.977867) ========= ========= Total master_lag: 0.38 =========