builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1469185679.33 results: success (0) buildid: 20160722013039 builduid: 0264508d29cf427f9bb156f4b17ee7ab revision: 21d15da870e8f74b933429f4b3efd651747b8b31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.328760) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.329177) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.329467) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.352185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.352493) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 04:07:59-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-07-22 04:07:59 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094786 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.461054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.461390) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021282 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.515497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:59.515813) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 21d15da870e8f74b933429f4b3efd651747b8b31 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 21d15da870e8f74b933429f4b3efd651747b8b31 --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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 04:07:59,645 truncating revision to first 12 chars 2016-07-22 04:07:59,645 Setting DEBUG logging. 2016-07-22 04:07:59,646 attempt 1/10 2016-07-22 04:07:59,646 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/21d15da870e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 04:07:59,946 unpacking tar archive at: mozilla-inbound-21d15da870e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642975 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:08:00.169212) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 04:08:00.169577) ========= script_repo_revision: 21d15da870e8f74b933429f4b3efd651747b8b31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 04:08:00.170017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 04:08:00.170323) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 04:08:00.177217) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-22 04:08:00.177598) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:00 INFO - MultiFileLogger online at 20160722 04:08:00 in /builds/slave/test 04:08:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:08:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:00 INFO - {'append_to_log': False, 04:08:00 INFO - 'base_work_dir': '/builds/slave/test', 04:08:00 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:08:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:00 INFO - 'branch': 'Mozilla-Inbound', 04:08:00 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:00 INFO - 'config_files': ('talos/linux_config.py',), 04:08:00 INFO - 'default_actions': ('clobber', 04:08:00 INFO - 'read-buildbot-config', 04:08:00 INFO - 'download-and-extract', 04:08:00 INFO - 'populate-webroot', 04:08:00 INFO - 'create-virtualenv', 04:08:00 INFO - 'install', 04:08:00 INFO - 'run-tests'), 04:08:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:00 INFO - 'download_minidump_stackwalk': True, 04:08:00 INFO - 'download_symbols': 'ondemand', 04:08:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:08:00 INFO - 'tooltool.py': '/tools/tooltool.py', 04:08:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:08:00 INFO - '/tools/misc-python/virtualenv.py')}, 04:08:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:00 INFO - 'installer_path': 'installer.exe', 04:08:00 INFO - 'log_level': 'info', 04:08:00 INFO - 'log_name': 'talos', 04:08:00 INFO - 'log_to_console': True, 04:08:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:00 INFO - 'opt_config_files': (), 04:08:00 INFO - 'pip_index': False, 04:08:00 INFO - 'sps_profile': False, 04:08:00 INFO - 'sps_profile_interval': 0, 04:08:00 INFO - 'suite': 'dromaeojs', 04:08:00 INFO - 'system_bits': '32', 04:08:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:00 INFO - 'title': 'talos-linux64-ix-012', 04:08:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:00 INFO - 'use_talos_json': True, 04:08:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:00 INFO - 'work_dir': 'build'} 04:08:00 INFO - [mozharness: 2016-07-22 11:08:00.402089Z] Running clobber step. 04:08:00 INFO - Running pre-action listener: _resource_record_pre_action 04:08:00 INFO - Running main action method: clobber 04:08:00 INFO - rmtree: /builds/slave/test/build 04:08:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:00 INFO - Running post-action listener: _resource_record_post_action 04:08:00 INFO - [mozharness: 2016-07-22 11:08:00.865814Z] Finished clobber step (success) 04:08:00 INFO - [mozharness: 2016-07-22 11:08:00.865915Z] Running read-buildbot-config step. 04:08:00 INFO - Running pre-action listener: _resource_record_pre_action 04:08:00 INFO - Running main action method: read_buildbot_config 04:08:00 INFO - Using buildbot properties: 04:08:00 INFO - { 04:08:00 INFO - "project": "", 04:08:00 INFO - "product": "firefox", 04:08:00 INFO - "script_repo_revision": "production", 04:08:00 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:08:00 INFO - "repository": "", 04:08:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:08:00 INFO - "buildid": "20160722013039", 04:08:00 INFO - "pgo_build": "True", 04:08:00 INFO - "basedir": "/builds/slave/test", 04:08:00 INFO - "buildnumber": 2108, 04:08:00 INFO - "slavename": "talos-linux64-ix-012", 04:08:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:08:00 INFO - "platform": "ubuntu64_hw", 04:08:00 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:08:00 INFO - "branch": "mozilla-inbound", 04:08:00 INFO - "repo_path": "integration/mozilla-inbound", 04:08:00 INFO - "revision": "21d15da870e8f74b933429f4b3efd651747b8b31", 04:08:00 INFO - "stage_platform": "linux64-pgo", 04:08:00 INFO - "builduid": "0264508d29cf427f9bb156f4b17ee7ab", 04:08:00 INFO - "slavebuilddir": "test" 04:08:00 INFO - } 04:08:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:08:00 INFO - Running post-action listener: _resource_record_post_action 04:08:00 INFO - [mozharness: 2016-07-22 11:08:00.868336Z] Finished read-buildbot-config step (success) 04:08:00 INFO - [mozharness: 2016-07-22 11:08:00.868425Z] Running download-and-extract step. 04:08:00 INFO - Running pre-action listener: _resource_record_pre_action 04:08:00 INFO - Running main action method: download_and_extract 04:08:00 INFO - mkdir: /builds/slave/test/build/tests 04:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:00 INFO - https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:08:01 INFO - Downloaded 1361 bytes. 04:08:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:08:01 INFO - Using the following test package requirements: 04:08:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:08:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:08:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:08:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'jsshell-linux-x86_64.zip'], 04:08:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:08:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:08:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:08:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:08:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:08:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:08:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:01 INFO - https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:03 INFO - Downloaded 24308744 bytes. 04:08:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:08:04 INFO - Return code: 0 04:08:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:04 INFO - https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:08:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:18 INFO - Downloaded 24308744 bytes. 04:08:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:08:19 INFO - Return code: 0 04:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:19 INFO - https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:08:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:08:21 INFO - Downloaded 14403317 bytes. 04:08:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:08:21 INFO - Return code: 0 04:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:21 INFO - https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:08:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:08:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:08:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:08:22 INFO - Downloaded 60855208 bytes. 04:08:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:08:22 INFO - mkdir: /builds/slave/test/properties 04:08:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:22 INFO - Contents: 04:08:22 INFO - build_url:https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:08:23 INFO - Running post-action listener: _resource_record_post_action 04:08:23 INFO - Running post-action listener: set_extra_try_arguments 04:08:23 INFO - [mozharness: 2016-07-22 11:08:23.479031Z] Finished download-and-extract step (success) 04:08:23 INFO - [mozharness: 2016-07-22 11:08:23.479357Z] Running populate-webroot step. 04:08:23 INFO - Running pre-action listener: _resource_record_pre_action 04:08:23 INFO - Running main action method: populate_webroot 04:08:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:08:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:08:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:08:23 INFO - '--tppagecycles', 04:08:23 INFO - '7'], 04:08:23 INFO - 'tests': ['tsvgm']}}, 04:08:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:08:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:08:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:08:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:08:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['damp', 'tps']}, 04:08:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'tests': ['damp', 'tps']}, 04:08:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['dromaeo_dom']}, 04:08:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:08:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['basic_compositor_video']}, 04:08:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:08:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['a11yr', 04:08:23 INFO - 'ts_paint', 04:08:23 INFO - 'tpaint', 04:08:23 INFO - 'sessionrestore', 04:08:23 INFO - 'sessionrestore_no_auto_restore', 04:08:23 INFO - 'tabpaint']}, 04:08:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['tsvgx', 04:08:23 INFO - 'tsvgr_opacity', 04:08:23 INFO - 'tart', 04:08:23 INFO - 'tscrollx', 04:08:23 INFO - 'cart']}, 04:08:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:08:23 INFO - 'tsvgr_opacity', 04:08:23 INFO - 'tart', 04:08:23 INFO - 'tscrollx', 04:08:23 INFO - 'cart']}, 04:08:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'talos_options': ['--disable-e10s'], 04:08:23 INFO - 'tests': ['tp5o']}, 04:08:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'tests': ['tp5o']}, 04:08:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'talos_options': ['--disable-e10s', 04:08:23 INFO - '--xperf_path', 04:08:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:23 INFO - 'tests': ['tp5n']}, 04:08:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:23 INFO - 'talos_options': ['--xperf_path', 04:08:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:23 INFO - 'tests': ['tp5n']}}, 04:08:23 INFO - 'talos.zip': {'path': '', 04:08:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:08:23 INFO - Running post-action listener: _resource_record_post_action 04:08:23 INFO - [mozharness: 2016-07-22 11:08:23.500093Z] Finished populate-webroot step (success) 04:08:23 INFO - [mozharness: 2016-07-22 11:08:23.500184Z] Running create-virtualenv step. 04:08:23 INFO - Running pre-action listener: _resource_record_pre_action 04:08:23 INFO - Running main action method: create_virtualenv 04:08:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:08:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:08:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:08:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:08:23 INFO - Using real prefix '/usr' 04:08:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:08:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:08:26 INFO - Installing pip.................done. 04:08:26 INFO - Return code: 0 04:08:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:26 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:08:26 INFO - Using env: {'DISPLAY': ':0', 04:08:26 INFO - 'HOME': '/home/cltbld', 04:08:26 INFO - 'LANG': 'en_US.UTF-8', 04:08:26 INFO - 'LANGUAGE': 'en_US:en', 04:08:26 INFO - 'LOGNAME': 'cltbld', 04:08:26 INFO - 'MAIL': '/var/mail/cltbld', 04:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:26 INFO - 'MOZ_NO_REMOTE': '1', 04:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:26 INFO - 'NO_EM_RESTART': '1', 04:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:26 INFO - 'PWD': '/builds/slave/test', 04:08:26 INFO - 'SHELL': '/bin/bash', 04:08:26 INFO - 'SHLVL': '1', 04:08:26 INFO - 'TERM': 'linux', 04:08:26 INFO - 'TMOUT': '86400', 04:08:26 INFO - 'USER': 'cltbld', 04:08:26 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:26 INFO - '_': '/tools/buildbot/bin/python'} 04:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:08:26 INFO - Cleaning up... 04:08:26 INFO - Return code: 0 04:08:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:26 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:08:26 INFO - Using env: {'DISPLAY': ':0', 04:08:26 INFO - 'HOME': '/home/cltbld', 04:08:26 INFO - 'LANG': 'en_US.UTF-8', 04:08:26 INFO - 'LANGUAGE': 'en_US:en', 04:08:26 INFO - 'LOGNAME': 'cltbld', 04:08:26 INFO - 'MAIL': '/var/mail/cltbld', 04:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:26 INFO - 'MOZ_NO_REMOTE': '1', 04:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:26 INFO - 'NO_EM_RESTART': '1', 04:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:26 INFO - 'PWD': '/builds/slave/test', 04:08:26 INFO - 'SHELL': '/bin/bash', 04:08:26 INFO - 'SHLVL': '1', 04:08:26 INFO - 'TERM': 'linux', 04:08:26 INFO - 'TMOUT': '86400', 04:08:26 INFO - 'USER': 'cltbld', 04:08:26 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:26 INFO - '_': '/tools/buildbot/bin/python'} 04:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:26 INFO - Downloading/unpacking psutil>=3.1.1 04:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:08:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:28 INFO - Installing collected packages: psutil 04:08:28 INFO - Running setup.py install for psutil 04:08:28 INFO - building 'psutil._psutil_linux' extension 04:08:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:08:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:08:28 INFO - building 'psutil._psutil_posix' extension 04:08:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:08:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:08:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:29 INFO - Successfully installed psutil 04:08:29 INFO - Cleaning up... 04:08:29 INFO - Return code: 0 04:08:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:29 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:08:29 INFO - Using env: {'DISPLAY': ':0', 04:08:29 INFO - 'HOME': '/home/cltbld', 04:08:29 INFO - 'LANG': 'en_US.UTF-8', 04:08:29 INFO - 'LANGUAGE': 'en_US:en', 04:08:29 INFO - 'LOGNAME': 'cltbld', 04:08:29 INFO - 'MAIL': '/var/mail/cltbld', 04:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:29 INFO - 'MOZ_NO_REMOTE': '1', 04:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:29 INFO - 'NO_EM_RESTART': '1', 04:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:29 INFO - 'PWD': '/builds/slave/test', 04:08:29 INFO - 'SHELL': '/bin/bash', 04:08:29 INFO - 'SHLVL': '1', 04:08:29 INFO - 'TERM': 'linux', 04:08:29 INFO - 'TMOUT': '86400', 04:08:29 INFO - 'USER': 'cltbld', 04:08:29 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:29 INFO - '_': '/tools/buildbot/bin/python'} 04:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:08:31 INFO - Installing collected packages: mozsystemmonitor 04:08:31 INFO - Running setup.py install for mozsystemmonitor 04:08:31 INFO - Successfully installed mozsystemmonitor 04:08:31 INFO - Cleaning up... 04:08:31 INFO - Return code: 0 04:08:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:31 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:08:31 INFO - Using env: {'DISPLAY': ':0', 04:08:31 INFO - 'HOME': '/home/cltbld', 04:08:31 INFO - 'LANG': 'en_US.UTF-8', 04:08:31 INFO - 'LANGUAGE': 'en_US:en', 04:08:31 INFO - 'LOGNAME': 'cltbld', 04:08:31 INFO - 'MAIL': '/var/mail/cltbld', 04:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:31 INFO - 'MOZ_NO_REMOTE': '1', 04:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:31 INFO - 'NO_EM_RESTART': '1', 04:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:31 INFO - 'PWD': '/builds/slave/test', 04:08:31 INFO - 'SHELL': '/bin/bash', 04:08:31 INFO - 'SHLVL': '1', 04:08:31 INFO - 'TERM': 'linux', 04:08:31 INFO - 'TMOUT': '86400', 04:08:31 INFO - 'USER': 'cltbld', 04:08:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:31 INFO - '_': '/tools/buildbot/bin/python'} 04:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:32 INFO - Downloading/unpacking jsonschema==2.5.1 04:08: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 04:08: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 04:08: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 04:08: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 04:08: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 04:08: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 04:08:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:08:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:34 INFO - Downloading functools32-3.2.3-2.tar.gz 04:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:08:34 INFO - warning: no files found matching '*.txt' 04:08:34 INFO - no previously-included directories found matching 'build' 04:08:34 INFO - no previously-included directories found matching 'dist' 04:08:34 INFO - no previously-included directories found matching '.git*' 04:08:34 INFO - Installing collected packages: jsonschema, functools32 04:08:34 INFO - Running setup.py install for functools32 04:08:34 INFO - warning: no files found matching '*.txt' 04:08:34 INFO - no previously-included directories found matching 'build' 04:08:34 INFO - no previously-included directories found matching 'dist' 04:08:34 INFO - no previously-included directories found matching '.git*' 04:08:34 INFO - Successfully installed jsonschema functools32 04:08:34 INFO - Cleaning up... 04:08:34 INFO - Return code: 0 04:08:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:34 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:08:34 INFO - Using env: {'DISPLAY': ':0', 04:08:34 INFO - 'HOME': '/home/cltbld', 04:08:34 INFO - 'LANG': 'en_US.UTF-8', 04:08:34 INFO - 'LANGUAGE': 'en_US:en', 04:08:34 INFO - 'LOGNAME': 'cltbld', 04:08:34 INFO - 'MAIL': '/var/mail/cltbld', 04:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:34 INFO - 'MOZ_NO_REMOTE': '1', 04:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:34 INFO - 'NO_EM_RESTART': '1', 04:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:34 INFO - 'PWD': '/builds/slave/test', 04:08:34 INFO - 'SHELL': '/bin/bash', 04:08:34 INFO - 'SHLVL': '1', 04:08:34 INFO - 'TERM': 'linux', 04:08:34 INFO - 'TMOUT': '86400', 04:08:34 INFO - 'USER': 'cltbld', 04:08:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:34 INFO - '_': '/tools/buildbot/bin/python'} 04:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:34 INFO - Downloading/unpacking blobuploader==1.2.4 04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:36 INFO - Downloading blobuploader-1.2.4.tar.gz 04:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:08:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:08:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:37 INFO - Downloading docopt-0.6.1.tar.gz 04:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:08:37 INFO - Installing collected packages: blobuploader, requests, docopt 04:08:37 INFO - Running setup.py install for blobuploader 04:08:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:08:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:08:37 INFO - Running setup.py install for requests 04:08:37 INFO - Running setup.py install for docopt 04:08:37 INFO - Successfully installed blobuploader requests docopt 04:08:37 INFO - Cleaning up... 04:08:37 INFO - Return code: 0 04:08:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:08:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:08:37 INFO - Using env: {'DISPLAY': ':0', 04:08:37 INFO - 'HOME': '/home/cltbld', 04:08:37 INFO - 'LANG': 'en_US.UTF-8', 04:08:37 INFO - 'LANGUAGE': 'en_US:en', 04:08:37 INFO - 'LOGNAME': 'cltbld', 04:08:37 INFO - 'MAIL': '/var/mail/cltbld', 04:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:37 INFO - 'MOZ_NO_REMOTE': '1', 04:08:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:37 INFO - 'NO_EM_RESTART': '1', 04:08:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:37 INFO - 'PWD': '/builds/slave/test', 04:08:37 INFO - 'SHELL': '/bin/bash', 04:08:37 INFO - 'SHLVL': '1', 04:08:37 INFO - 'TERM': 'linux', 04:08:37 INFO - 'TMOUT': '86400', 04:08:37 INFO - 'USER': 'cltbld', 04:08:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:37 INFO - '_': '/tools/buildbot/bin/python'} 04:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:38 INFO - Running setup.py (path:/tmp/pip-TvHDd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:38 INFO - Running setup.py (path:/tmp/pip-MdSq9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:38 INFO - Running setup.py (path:/tmp/pip-LrWZWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:38 INFO - Running setup.py (path:/tmp/pip-4pSMQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:38 INFO - Running setup.py (path:/tmp/pip-UHTQmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:38 INFO - Running setup.py (path:/tmp/pip-2ZwBwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:38 INFO - Running setup.py (path:/tmp/pip-mG21B3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:38 INFO - Running setup.py (path:/tmp/pip-_RObGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:38 INFO - Running setup.py (path:/tmp/pip-bp6_8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:38 INFO - Running setup.py (path:/tmp/pip-wqCV4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:38 INFO - Running setup.py (path:/tmp/pip-zPwjvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:39 INFO - Running setup.py (path:/tmp/pip-wf5kWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:39 INFO - Running setup.py (path:/tmp/pip-YTlI2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:39 INFO - Running setup.py (path:/tmp/pip-hpAuHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:39 INFO - Running setup.py (path:/tmp/pip-qG7ccn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:39 INFO - Running setup.py (path:/tmp/pip-BpXIJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:39 INFO - Running setup.py (path:/tmp/pip-dzgNtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:08:39 INFO - Running setup.py install for manifestparser 04:08:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:08:39 INFO - Running setup.py install for mozcrash 04:08:39 INFO - Running setup.py install for mozdebug 04:08:39 INFO - Running setup.py install for mozdevice 04:08:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:08:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:08:39 INFO - Running setup.py install for mozfile 04:08:39 INFO - Running setup.py install for mozhttpd 04:08:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozinfo 04:08:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozInstall 04:08:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozleak 04:08:40 INFO - Running setup.py install for mozlog 04:08:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for moznetwork 04:08:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozprocess 04:08:40 INFO - Running setup.py install for mozprofile 04:08:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozrunner 04:08:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:08:40 INFO - Running setup.py install for mozscreenshot 04:08:41 INFO - Running setup.py install for moztest 04:08:41 INFO - Running setup.py install for mozversion 04:08:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:08:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:08:41 INFO - Cleaning up... 04:08:41 INFO - Return code: 0 04:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:41 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:08:41 INFO - Using env: {'DISPLAY': ':0', 04:08:41 INFO - 'HOME': '/home/cltbld', 04:08:41 INFO - 'LANG': 'en_US.UTF-8', 04:08:41 INFO - 'LANGUAGE': 'en_US:en', 04:08:41 INFO - 'LOGNAME': 'cltbld', 04:08:41 INFO - 'MAIL': '/var/mail/cltbld', 04:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:41 INFO - 'MOZ_NO_REMOTE': '1', 04:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:41 INFO - 'NO_EM_RESTART': '1', 04:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:41 INFO - 'PWD': '/builds/slave/test', 04:08:41 INFO - 'SHELL': '/bin/bash', 04:08:41 INFO - 'SHLVL': '1', 04:08:41 INFO - 'TERM': 'linux', 04:08:41 INFO - 'TMOUT': '86400', 04:08:41 INFO - 'USER': 'cltbld', 04:08:41 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:41 INFO - '_': '/tools/buildbot/bin/python'} 04:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:41 INFO - Running setup.py (path:/tmp/pip-hdgjH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:41 INFO - Running setup.py (path:/tmp/pip-myf8BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:41 INFO - Running setup.py (path:/tmp/pip-WzSKOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:41 INFO - Running setup.py (path:/tmp/pip-36aW_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:41 INFO - Running setup.py (path:/tmp/pip-zFj_We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:41 INFO - Running setup.py (path:/tmp/pip-guLZ5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:41 INFO - Running setup.py (path:/tmp/pip-mQJrta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:41 INFO - Running setup.py (path:/tmp/pip-Wfh3Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:42 INFO - Running setup.py (path:/tmp/pip-Y13mAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:42 INFO - Running setup.py (path:/tmp/pip-LbHRAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:42 INFO - Running setup.py (path:/tmp/pip-wTxoxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:42 INFO - Running setup.py (path:/tmp/pip-PCBdIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:42 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)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:42 INFO - Running setup.py (path:/tmp/pip-S8vZdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:42 INFO - Running setup.py (path:/tmp/pip-BL1oGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:42 INFO - Running setup.py (path:/tmp/pip-6V9Etm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:42 INFO - Running setup.py (path:/tmp/pip-8Qdk2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:42 INFO - Running setup.py (path:/tmp/pip-XaVi8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:08:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:44 INFO - Downloading blessings-1.6.tar.gz 04:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:08:44 INFO - Installing collected packages: blessings 04:08:44 INFO - Running setup.py install for blessings 04:08:44 INFO - Successfully installed blessings 04:08:44 INFO - Cleaning up... 04:08:44 INFO - Return code: 0 04:08:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:45 INFO - Reading from file tmpfile_stdout 04:08:45 INFO - Current package versions: 04:08:45 INFO - argparse == 1.2.1 04:08:45 INFO - blessings == 1.6 04:08:45 INFO - blobuploader == 1.2.4 04:08:45 INFO - docopt == 0.6.1 04:08:45 INFO - functools32 == 3.2.3-2 04:08:45 INFO - jsonschema == 2.5.1 04:08:45 INFO - manifestparser == 1.1 04:08:45 INFO - mozInstall == 1.12 04:08:45 INFO - mozcrash == 0.17 04:08:45 INFO - mozdebug == 0.1 04:08:45 INFO - mozdevice == 0.48 04:08:45 INFO - mozfile == 1.2 04:08:45 INFO - mozhttpd == 0.7 04:08:45 INFO - mozinfo == 0.9 04:08:45 INFO - mozleak == 0.1 04:08:45 INFO - mozlog == 3.3 04:08:45 INFO - moznetwork == 0.27 04:08:45 INFO - mozprocess == 0.23 04:08:45 INFO - mozprofile == 0.28 04:08:45 INFO - mozrunner == 6.12 04:08:45 INFO - mozscreenshot == 0.1 04:08:45 INFO - mozsystemmonitor == 0.3 04:08:45 INFO - moztest == 0.7 04:08:45 INFO - mozversion == 1.4 04:08:45 INFO - psutil == 3.1.1 04:08:45 INFO - requests == 1.2.3 04:08:45 INFO - wsgiref == 0.1.2 04:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:45 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:08:45 INFO - Using env: {'DISPLAY': ':0', 04:08:45 INFO - 'HOME': '/home/cltbld', 04:08:45 INFO - 'LANG': 'en_US.UTF-8', 04:08:45 INFO - 'LANGUAGE': 'en_US:en', 04:08:45 INFO - 'LOGNAME': 'cltbld', 04:08:45 INFO - 'MAIL': '/var/mail/cltbld', 04:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:45 INFO - 'MOZ_NO_REMOTE': '1', 04:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:45 INFO - 'NO_EM_RESTART': '1', 04:08:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:45 INFO - 'PWD': '/builds/slave/test', 04:08:45 INFO - 'SHELL': '/bin/bash', 04:08:45 INFO - 'SHLVL': '1', 04:08:45 INFO - 'TERM': 'linux', 04:08:45 INFO - 'TMOUT': '86400', 04:08:45 INFO - 'USER': 'cltbld', 04:08:45 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:45 INFO - '_': '/tools/buildbot/bin/python'} 04:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:08:45 INFO - Cleaning up... 04:08:45 INFO - Return code: 0 04:08:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:45 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 0x29d5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:08:45 INFO - Using env: {'DISPLAY': ':0', 04:08:45 INFO - 'HOME': '/home/cltbld', 04:08:45 INFO - 'LANG': 'en_US.UTF-8', 04:08:45 INFO - 'LANGUAGE': 'en_US:en', 04:08:45 INFO - 'LOGNAME': 'cltbld', 04:08:45 INFO - 'MAIL': '/var/mail/cltbld', 04:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:45 INFO - 'MOZ_NO_REMOTE': '1', 04:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:45 INFO - 'NO_EM_RESTART': '1', 04:08:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:45 INFO - 'PWD': '/builds/slave/test', 04:08:45 INFO - 'SHELL': '/bin/bash', 04:08:45 INFO - 'SHLVL': '1', 04:08:45 INFO - 'TERM': 'linux', 04:08:45 INFO - 'TMOUT': '86400', 04:08:45 INFO - 'USER': 'cltbld', 04:08:45 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:45 INFO - '_': '/tools/buildbot/bin/python'} 04:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:08:45 INFO - Cleaning up... 04:08:45 INFO - Return code: 0 04:08:45 INFO - Running post-action listener: _resource_record_post_action 04:08:45 INFO - Running post-action listener: _start_resource_monitoring 04:08:45 INFO - Starting resource monitoring. 04:08:45 INFO - [mozharness: 2016-07-22 11:08:45.549830Z] Finished create-virtualenv step (success) 04:08:45 INFO - [mozharness: 2016-07-22 11:08:45.550315Z] Running install step. 04:08:45 INFO - Running pre-action listener: _resource_record_pre_action 04:08:45 INFO - Running main action method: install 04:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:45 INFO - Reading from file tmpfile_stdout 04:08:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:08:45 INFO - Reading from file tmpfile_stdout 04:08:45 INFO - Output received: 04:08:45 INFO - Usage: mozinstall [options] installer 04:08:45 INFO - Options: 04:08:45 INFO - -h, --help show this help message and exit 04:08:45 INFO - -d DEST, --destination=DEST 04:08:45 INFO - Directory to install application into. [default: 04:08:45 INFO - "/builds/slave/test"] 04:08:45 INFO - --app=APP Application being installed. [default: firefox] 04:08:45 INFO - mkdir: /builds/slave/test/build/application 04:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:09:03 INFO - Reading from file tmpfile_stdout 04:09:03 INFO - Output received: 04:09:03 INFO - /builds/slave/test/build/application/firefox/firefox 04:09:03 INFO - Running post-action listener: _resource_record_post_action 04:09:03 INFO - [mozharness: 2016-07-22 11:09:03.065930Z] Finished install step (success) 04:09:03 INFO - [mozharness: 2016-07-22 11:09:03.066215Z] Running run-tests step. 04:09:03 INFO - Running pre-action listener: _resource_record_pre_action 04:09:03 INFO - Running main action method: run_tests 04:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:03 INFO - Python 2.7.3 04:09:03 INFO - Return code: 0 04:09:03 INFO - grabbing minidump binary from tooltool 04:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:03 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 0x2c943b0>, '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 0x2c952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c95740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:09:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:09:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:09:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:03 INFO - Return code: 0 04:09:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:09:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:09:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:09:03 INFO - Using env: {'DISPLAY': ':0', 04:09:03 INFO - 'HOME': '/home/cltbld', 04:09:03 INFO - 'LANG': 'en_US.UTF-8', 04:09:03 INFO - 'LANGUAGE': 'en_US:en', 04:09:03 INFO - 'LOGNAME': 'cltbld', 04:09:03 INFO - 'MAIL': '/var/mail/cltbld', 04:09:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:03 INFO - 'MOZ_NO_REMOTE': '1', 04:09:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:03 INFO - 'NO_EM_RESTART': '1', 04:09:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:03 INFO - 'PWD': '/builds/slave/test', 04:09:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:03 INFO - 'SHELL': '/bin/bash', 04:09:03 INFO - 'SHLVL': '1', 04:09:03 INFO - 'TERM': 'linux', 04:09:03 INFO - 'TMOUT': '86400', 04:09:03 INFO - 'USER': 'cltbld', 04:09:03 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364', 04:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:03 INFO - '_': '/tools/buildbot/bin/python'} 04:09:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:09:03 INFO - mozversion application_buildid: 20160722013039 04:09:03 INFO - mozversion application_changeset: 21d15da870e8f74b933429f4b3efd651747b8b31 04:09:03 INFO - mozversion application_display_name: Nightly 04:09:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:03 INFO - mozversion application_name: Firefox 04:09:03 INFO - mozversion application_remotingname: firefox 04:09:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:09:03 INFO - mozversion application_vendor: Mozilla 04:09:03 INFO - mozversion application_version: 50.0a1 04:09:03 INFO - mozversion platform_buildid: 20160722013039 04:09:03 INFO - mozversion platform_changeset: 21d15da870e8f74b933429f4b3efd651747b8b31 04:09:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:09:03 INFO - mozversion platform_version: 50.0a1 04:09:03 INFO - using testdate: 1469185743 04:09:03 INFO - actual date: 1469185743 04:09:03 INFO - starting webserver on 'localhost:48631' 04:09:03 INFO - SUITE-START | Running 2 tests 04:09:03 INFO - TEST-START | dromaeo_css 04:09:03 INFO - operating with platform_type : linux_ 04:09:03 INFO - Initialising browser for dromaeo_css test... 04:09:03 INFO - TEST-INFO | started process 6254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFh3sGM/profile http://localhost:48631/getInfo.html) 04:09:06 INFO - PROCESS | 6254 | JavaScript error: , line 0: uncaught exception: undefined 04:09:07 INFO - PROCESS | 6254 | __metrics Screen width/height:1600/1200 04:09:07 INFO - PROCESS | 6254 | colorDepth:24 04:09:07 INFO - PROCESS | 6254 | Browser inner width/height: 1024/697 04:09:07 INFO - PROCESS | 6254 | __metrics 04:09:07 INFO - PROCESS | 6254 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:09:07 INFO - PROCESS | 6254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:08 INFO - TEST-INFO | 6254: exit 0 04:09:08 INFO - Browser initialized. 04:09:08 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:08 INFO - TEST-INFO | started process 6333 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFh3sGM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:20 INFO - PROCESS | 6333 | RSS: Main: 157921280 04:09:20 INFO - PROCESS | 6333 | 04:10:12 INFO - PROCESS | 6333 | 1469185812289 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:12:20 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48631/tests/dromaeo/cssquery-ext.html) 04:12:21 INFO - PROCESS | 6333 | RSS: Main: 268185600 04:12:21 INFO - PROCESS | 6333 | 04:15:23 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-ext.html (next: http://localhost:48631/tests/dromaeo/cssquery-jquery.html) 04:15:24 INFO - PROCESS | 6333 | RSS: Main: 242962432 04:15:24 INFO - PROCESS | 6333 | 04:18:25 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48631/tests/dromaeo/cssquery-mootools.html) 04:18:26 INFO - PROCESS | 6333 | RSS: Main: 187666432 04:18:26 INFO - PROCESS | 6333 | 04:21:28 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48631/tests/dromaeo/cssquery-prototype.html) 04:21:29 INFO - PROCESS | 6333 | RSS: Main: 217739264 04:21:29 INFO - PROCESS | 6333 | 04:24:31 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48631/tests/dromaeo/cssquery-yui.html) 04:24:32 INFO - PROCESS | 6333 | RSS: Main: 186617856 04:24:32 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-yui.html (next: http://localhost:48631/tests/dromaeo/cssquery-dojo.html) 04:27:35 INFO - PROCESS | 6333 | RSS: Main: 278687744 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | __start_tp_report 04:27:35 INFO - PROCESS | 6333 | _x_x_mozilla_page_load 04:27:35 INFO - PROCESS | 6333 | _x_x_mozilla_page_load_details 04:27:35 INFO - PROCESS | 6333 | |i|pagename|runs| 04:27:35 INFO - PROCESS | 6333 | |0;dojo.html;3042.513197888338;3072.8156310621353;3102.177922850444;3105.363400502897;3117.392108538836;5297.947020529794;5358.437364076774;5365;5430.72846357682;5483.5613150947975;5367.812126575571;5398.838034858951;5422.783088676458;5464.289642346489;5486.478784515474;4841.661083724141;4943.629227807911;4949.109160351135;4957.801687932479;5094.541491265785;4990.251462280668;5021.698698078127;5046.697198168104;5055.140626093555;5060.291559181717;4819.084373968934;4850.345203397532;4893.486183950669;4896.069746748107;4964.032013757319;4863.513648635144;4868.683535570212;4870.14772414826;4922.975385123087;5000.399952005746;4865.927011094836;4902.828401005983;4923.753812309406;4925.236588328779;4941.036497882914;5344.278522399504;5386.67679939201;5431.429699881495;5455.809046683386;5459.426760190163;5858.53131749459;5862.794802181903;5892.734826932756;5906.232189815297;5909.940900590982;5905.557083218776;5937.495312898409;5939.524838012949;5956.493698035672;5957.225560677128;5320.574354051667;5464.426235245322;5530.446955304518;5535.612507124503;5543.77824887004;5976.5218782497295;6027.186329845415;6029.457348838538;6031;6032.336442991267;5805.8548536286935;5812.041013232853;5812.738426770806;5818.796342128006;5824.446677565625;5666.405027472056;5734.369219385864;5791.797287094932;5795.478406943395;5899.4985426239255;5791;5797.623154495029;5802.3037235531165;5803.100519419497;5849.034909239927;5728.6276392035215;5743.712814359349;5757.222774925415;5789.102689083199;5789.102689083199;4342.348647702806;4349.108432771274;4351.477822661301;4386.890327741832;4413.691041627056;3933.8819835404984;3936.6063393660406;3963.6829053675638;3972.3842804365368;3989.9202015959518;5489.64317319373;5498.835034948958;5504.284443022461;5505.174223866452;5506.091494903376;5661.377248502661;5774.451427114417;5821.214136091573;5837.357890632027;5879.823605291848;5503.394626591072;5508.834734957958;5517.806876759374;5520.613557051048;5562.499375056264;5023.623228257895;5027;5027.547520723152;5029.748512574357;5079.822206222764;5792.218050563204;5917.437843404869;5980.730867110903;5991.520678345809;6004.939950600526;5861.706914654165;5862.530997520103;5863.941360586339;5902.793402230901;5926.674032928058;5984.820455386345;5992.610480318765;6001.219841420588;6003.579749417673;6004.099385092097;5585.441455854382;5634.183043458757;5658.292713410824;5690.402507736793;5767.452092051249;5754.856128596819;5801.245838041028;5808.01263785166;5831.591788574929;5850.268716410449;5647.096464565814;5648.85877853057;5649.152627105967;5654.208410822406;5677.034904066236;5467.535259502828;5573.693446860461;5650.63270887391;5666.943330566806;5673.602847800779;5664.235328230801;5664.886702266014;5677.51741102011;5695.401982791747;5722.856928576819;5652.63257888236;5725.169850371756;5756.597038207453;5761.049426844524;5772.682502462237;5358.571314294769;5464.590155738256;5550.916736249041;5571.498565129158;5573.996680597369 04:27:35 INFO - PROCESS | 6333 | |1;ext.html;585.2070444547638;612.7395856760877;630.3664816859056;634.7016902056032;648.8961766117422;30391;43550.56449435505;44663;44707.329390059174;44723.10553788922;45667.08665826682;46348.53651463484;46361;46374;46403.303950440684;8967.775805604846;9101.180893719564;9113.863292050624;9117.49853758043;9122.407043542182;8989.95505022474;9084.09159084093;9091.63633454661;9093.772655683595;9098.090190980914;9095.272378209727;9134.908650913472;9136.954315228415;9143.131402516783;9146.31402644801;9054.049324820899;9059.547022648841;9084.182423581877;9103.499307538083;9116;8954.507502087383;9006.054364291786;9026.729198124067;9026.729198124067;9041.683541076096;43235;43724.344134838;44015.11969760619;44019.119617607466;44039.77980110111;3664.157243833933;3708.8887333380044;3739.925201495982;3838.500994870677;4233.111046680201;32931.83534082338;33413.498797518056;33902.32195356079;34599.308013839836;34637.4804377932;4302.354646802974;4365.083332500179;4410;4468.329750537413;4502.41468609082;381727;385787;386758;386790.9999999972;387372;50531.24203136956;56964.71517642385;57256.71371643115;57267;57299.71350143223;59105;60478.39521604816;60507.69746151241;65233;65251.67374163099;16945.915270423568;16978.49064528066;16980.74528882068;17137.571560710832;17140;13425;13496;13500.392482338319;13501.459941602423;13502.459901604023;16031;16042.037477751372;16048.358065677243;16100.597485062966;16118.355265789238;5396.811111611075;6385.13800636908;6407.359264073555;6524.380183882524;6586.5389422739945;976.3360914578155;1079.2337440417234;1106.435717783936;1150.8216226484908;1157.6584907452323;32137.196570085467;32570.511442328385;32801.671983280336;33005.33989320248;33408.49887251693;1059.1473863539925;1111.3054341036852;1161.4483120517687;1174.236746115032;1202.8676845546984;16785.664286714196;17103.74344384835;17150.828491715172;17342.007990514958;17450.563735906704;514257;519652;520096;520738;521104;58742.706286468296;58853.41146588565;58864.11703824446;59005.40994590085;59229.70385148133;1358.7554240236852;1365.180891465113;1401.1453013661658;1412.9222892740997;1413.9293035348192;31383;32022.519662205552;33145;33327.166820829676;33588.49617255792;48751.26873096977;49209;49265;49314.75342623264;49340.25989610087;415290;415576;416972;417645;418170;27247;27435.17694469169;27592.862035689694;27766;27893.581596275668;34025.65974340225;45863.54136458593;47159.764201178776;47243.763781180875;47931;38900.41649375318;40322.395164071975;40410.79794601008;40447.393289101274;41720.582794172886;38022.809885950395;39855.40216896691;39898.601013989486;39988.800055999534;40549.1890162201;38998.2200355997;39763.204735905696;40028.59971400365;40139.982502187224;40543;33883.83058084694;35935.83636172759;36282.274354512236;36510.8174459126;36540.45189322109;27080.1875943722;27288;27634.447311053264;27771.583426248217;27827.860860695568 04:27:35 INFO - PROCESS | 6333 | |2;jquery.html;359.4787558040841;360.95127157833684;362.8875048734893;373.84111752505197;380.4616467698204;18879.43361699148;19033.238670453182;19371.32200372987;19512.024398780075;19661.410157695293;18059.458216253533;18235.72646410305;18599;18872.528186795338;18904.338348157817;5484.369297530785;5691.9146212806845;5694.6867922264255;5695.629784064044;5698.34469036062;5606.131049687285;5849.7660093596205;5906.793262235823;5909.4090590941;5942.732577034044;5625.634333768292;5905.7342419591005;5946.702664866761;5951.315598706139;5952.7916522921705;5818.359980402152;5970.820875373745;5971.820845374646;5975.641461512279;5984.162217289583;5574.191742197399;5702.287214098238;5761.9135712964335;5785.363610002896;5791.884162316772;11769.293842369485;11830.053595712323;11849.466773995191;11872;12726.17279876805;2222.488827569668;2241.7421996470453;2276.066812606823;2338.918137865184;2468.7037555493275;9709.563069661883;10194.898051019543;10223.693289201336;11686.24039437434;13981.860181398157;1862.6647203503364;1976.3873199308262;1983.1769815526538;2010.1858747207405;2018.2835093541728;77948.61025694892;78049.21950780477;79330.20669793285;79996.80004799932;82216.58891705563;9973.601055957826;10090.192784577217;10211.693649190536;10509.894901050891;10675.558799561959;14328.713425731636;14331.928340358232;14357.712845743026;14436.278186090654;14453.927730361282;7882.802929926797;8095.919040809635;8145.3483721302155;8269;8333.208345207195;7658.425618078666;7839.686412543549;7849.233922367536;8099.271065604124;8232.794180145425;4432.512423633398;4438.489573699066;4446.933296000562;4487.371767952489;4548.363229147922;1764.7441121037377;1852.638735446602;1877.2209533043633;1884.9623007539772;1890.1966664167674;913.5569248914271;1003.4982508745627;1011.7217765114652;1070.539667942792;1079.994600026995;19040.62065724891;19674.803251967296;19687.21251149938;19700.70448943267;19867.801321986884;981.8625392445064;984.2421335571713;998.1415982255255;1018.8217062014118;1026.6355443817486;10512.369257844553;10786.892131078746;10797.946010269898;10816.459177041117;10849;493399;494534;507425.9430542724;528029;529006;96250.51874740582;96623.51688241513;98522.50738746404;99252.5037374823;102180;981.5239608789731;1005.6731562242301;1007.9718686939281;1019.8164330420448;1027.6249169053242;16416;16792.412265571136;17224.311027558757;17260.827391726423;17519.386821461187;42753.572464274956;43941.462048828136;44203.778981106174;44396.55603443924;44772.77613611911;62129.68935155295;62431;62674;63757.36242637514;63811.36188638054;6636.402723754885;7206.135263768381;7612.923870761221;8119.715809946623;8325.417220794729;11575.884241157817;13178.538751143664;13229.867701322864;13286.933565332112;13318.200907945555;11296.943515282372;11487.540498380306;11775.411229438665;11900.821487677866;11905.583304584297;10965.232433729563;10975.670729878117;11341.546338146381;11447.885521144683;11818.704532386759;6292.339304372975;7893.763187104396;11769.646910592695;11801.763964720829;11841.111916606116;7259.2377800330205;7400.185979542139;7435.7025718970635;7713.460057796125;7839.549683308606;8288.129746376544;8746.562671866508;11426.885731142582;11465.254758440675;11498.597549086076 04:27:35 INFO - PROCESS | 6333 | |3;mootools.html;1233.46960806853;1272.694553307206;1329.8470675872277;1367.3778430813982;1427.5217802036318;7599.164091949881;8429.578521073945;8631.395802293842;8651.653933842646;8907;8685.696000639979;8718.782030449242;8758.693445729401;8768.868466972985;8778.253848422892;2260.6156953317895;2283.235116236063;2285.371522831218;2287.0965968442456;2287.325239054483;2244.607193741097;2280.395695140789;2295.0360848443693;2298.1267118495034;2324.639680849471;2247.2471721973075;2266.8526545774553;2272.261515007621;2297.5864344417996;2307.676925230469;2196.5497073099978;2204.459907322708;2207.3267653365715;2212.6127927612683;2214.734231892175;2233.452804062999;2238.2054370698493;2239.4177513846353;2242.316093591454;2246.225052356934;8612.8708069379;8613.827723445496;8719.738407847775;8720.563971801384;8725.912740872573;4823.903521929542;4936.111499930011;4938.827141050082;4945.431275403339;4981.402231732179;3094.6441159266747;3158.5735925649974;3225.532297816827;3225.629052658951;3232.870685172614;1140.5437824870035;1151.153901882116;2110.883901385423;2135.7864213578673;2190.9014094365793;11605.129615278804;11626.360550169737;11643.941780291128;11664.416779161009;11675.532978680927;4025.255327764373;4154.688398370135;4224.408582798411;4225.260579398593;4259.659227261812;9272.907270927339;9273.304502162366;9278.443293402417;9280.582373793195;9313.022132675971;4929.0388374267395;4934.55588996992;4941.308216849644;4941.505849415102;4943.184374578154;4205.306124489425;4488.371627972087;4578.88552786183;4612.815487380534;4649.976750116228;3460.653934606519;3570.589382221078;3608.440691692792;3630.7458477906293;3640.9635903640624;1023.6007956896816;1034.539629864718;1041.4063983529468;1046.7487802927242;1063.5852017713098;956.4929252164962;990.7325022243954;994.6518718448485;1024.3341827811832;1032.6282538286212;6059;6170.907436388457;6186.1648677428875;6220.284667263232;6333.113364129025;416.95205051418867;427.5169058963352;436.18651215482896;436.5198281889959;438.2068456094479;4418.293073108352;4429.911401772011;4432.800523976429;4497.123061003138;4528.252838281646;10009.249306302056;10245.897541024495;10274.948625256975;10365.274430789772;10373.636922707668;10096.798064038678;10260.640877569249;10311.432871192006;10319.638812641522;10348.741281468023;7664.540127592362;7775.727849525239;7833.52998820084;7838.294553490213;7843.098043725045;5834.474897259267;5896.941030589811;5983.970080149746;6000.729967151401;6073.453389194994;9337.439753614804;9432.103950125002;9447.055294470498;9679.370840895319;9760.316777825485;9224.24739299531;9787.755306117404;9811.901880981099;9895.802083958424;9917.950410247902;6662.967240077795;7105.32499412555;7114;7198.064251647253;7344.22885597026;8520.318374530147;8582.0988796178;8586.184312490303;8677;8678;7651.158372579123;7716.112647045437;7841.451098423284;7974.601269936364;8010.158933311918;7271.8909216360735;7412.332890039922;7463.813404664927;7509.662065207188;7525.3227209551405;6923.9307606923285;7005.894911576229;7011.6494175292055;7012.649367531501;7023.894641580483;5278.60410469224;5289.338832645919;5302.575793936553;5307.814226502054;5515.924394742987;5373.408925018323;5392.379876314118;5400.351957765093;5495.065838807493;5568.55451563882 04:27:35 INFO - PROCESS | 6333 | |4;prototype.html;310.89012226351906;314.306953168264;321.8873394311991;324.1377934693715;325.0929905563478;5300.469953004697;5376.462353764627;5378.489043540867;5435.755391007404;5438.646487978283;5050.752415175249;5102.015321037188;5221.634485586011;5234.973825130878;5324.387695415028;4117.773522456264;4118.19695159444;4127.958720412803;4136.31750761124;4160.937585936198;4152.1072969311745;4154.169166166779;4178.456800615916;4180.28935081037;4184.832606695729;3952.2095580883793;3962.8216730247063;4087.1825634872994;4125.484314460692;4158.334666453368;4098.479435147393;4120.814563344657;4124.917501649965;4126.133511962492;4148.647364973981;3675.117971686803;3677.669009789132;3681.9197153608393;3692.7968961707097;3693.630636936312;5136.948630513722;5201.895962080737;5202.661826981234;5242.423333433319;5262.500062494057;4712.952870471286;4716.716996980193;4744.667873248874;4753.500882407332;4758.834730197752;5485.286912701324;5501.284832971728;5504.614676972614;5521.917171242435;5530.861728456781;3961.306771315037;3986.60133986599;3991.1818077294083;4049.9392509112386;4050.615191556827;5662.6036177467595;5672.078879377124;5673.631213971079;5681.886362272731;5697.1739097831;4116.58834116586;4130.483689538808;4135.359019352005;4138.110306284103;4149.087200815816;4025.9798701006307;4157.750534967911;4192.874213773592;4194.622483976456;4197.349410841324;4667.743274119888;4679.157751604744;4689.850986508327;4695.2252878275385;4733.502982186821;5495.395506494282;5512.283403157564;5512.889742205133;5515.393306736256;5523.447655234525;1380.7652699041187;1392.2412285304533;1412.7668934625876;1426.4935947738404;1430.577979496051;908.182635627927;916.4043371808244;917.7338571814099;922.7957515442278;922.8292765838341;4749.1926372516755;4756.453007904066;4782.593479554207;4825.710457372569;4863.537963893425;4667.883302917454;4975.378077740283;5004.799808007711;5041.974790126026;5070.340855688737;4737.010455608653;4754.286856971413;4757.286407038902;4761.262004389325;4764.356811830358;2654.1772050664354;2659.404357385592;2667.852823286006;2671.49241644087;2693.8922443102056;4938.744633401145;5035.79856805731;5094.235864620263;5133.537981581676;5136.640435169577;5070.670406423571;5077.822276220389;5079.4412614612365;5092.490750924878;5093.388793344822;5122.872967947045;5146.202338637592;5189.728516513478;5192.688438693691;5275.762590683428;4927.334809800631;4968.627352948615;5353.9732301338245;5694.886102278014;5763.011760942468;5616.719164041863;5663.858403539944;5713.314402271754;5719.084946408555;5731.627444216113;5764.769409223752;5936.257967754031;5998.190244316961;6011.248593925759;6013.5189184865985;3952.446657468014;3962.445257663872;3964.3657014878636;3988.760674359548;3989.840406383712;5514.503694667499;5634.352049514358;5653.2085508028085;5668.242844718151;5789.739461724317;5447.155690867763;5622.18478320633;5624.775008999758;5633.155026745857;5690.203371528072;5335.893282134413;5597.893169291083;5598.384177740364;5635.295058371922;5662.065759149694;5580.637258578179;5590.357108932362;5606.635568688022;5619.831405057855;5635.098384258663;5627.127795191752;5676.77292908279;5683.687397193194;5732.684702341411;5743.8564035899435;5468.316460442445;5517.282753242052;5530.11518157109;5537.390887002507;5634.88730225385 04:27:35 INFO - PROCESS | 6333 | |5;yui.html;710.978670639881;736.5212611802327;748.1022772672791;784.7645706288113;829.6473998550614;15509.534713958572;16502.66994660106;16504.42234521792;16510.75233871493;16524.669506609862;13821.37803798829;13943.16341019537;13945.442182312696;14051.367688454018;14079.5072172474;658.2200092889917;790.7351037402477;803.2008151888864;816.0330008939396;821.9383546234056;801.6673080671515;812.4800127918137;812.9430939834214;812.979675508114;812.9918700812975;850.8596081646521;852.2585350744861;854.3293514591047;854.4531499840107;855;682.9419499342537;827.5903427803275;849.7110982266029;852.6205838401913;853.5262929074361;622.9065640153968;623.1027320658237;623.2365352443267;623.3828509775335;623.4887392338285;15110;15213.543593692208;15221.162836044014;15222.086674799548;15228.695426091415;14048.648783780385;14388.424463021467;14512.56462306122;14550;14600.415983360654;12967.546135885294;13169.736605267937;13196.14225075367;13239.20564766117;13641.181529108286;4429.955700442986;4530.569595888385;4559.247724125516;4564.863054108382;4613.238815595423;67179.3282067178;68188.31811681869;68576.31423685749;68784;76463.23536764616;11245.15661325404;11303.60437384704;11423.657290281304;11473.36896470702;11750.529978800923;22222.66666000011;22293.331200064025;22341.441463963205;22434.77565224327;22441;8484.660613575388;8534.70128545498;8672.436291641023;8726.476411415335;8811.515366654896;4400.867973960786;4549.909001820011;4606.170889239969;4610.815567377334;4743.786529606176;3511.1046683096156;3594.514740509998;3597.982010089933;3598.9280214395567;3605.387084195693;626.2547568393597;630.2657404124236;631.7978246960928;649.1528555235369;651.5211319680031;627.626562195493;658.3087757854233;664.3323459922747;681.5297444763098;711.850511392603;9094.454332740057;9408.153266205938;9577.664781732607;9593.42439453635;10069.044879171526;656.8456412743002;676.1210426445601;680.4114441008591;685.6605980039913;711.494838664554;4429.512753597101;4701.95298047022;4767.237242041326;4804.8318308859025;4839.975800120977;110812.4459377698;112186;112690.43654781673;116181;117517;65051.674741626935;68740.3125968734;95199;99813.50093249488;100982;81640.18359816444;86059.56970215109;88475;89361;90578.09421905829;9190.172884440433;9207.40151890125;9291.48896810682;9438.764030899283;9457.959624441444;52912.47087529075;53524.464755351946;53828.19257711059;54545.72727136339;54584.72707636436;66412.33587664062;73083;73363.63318183375;74282.62858685672;76250.61874690591;11262.211645184758;11431.828522572001;11938.761224775282;12206.6948326289;12227.572034979088;12944.935275323563;13028.869711303145;13042.673933151747;13310.534131305358;13541.390637421537;12074.879251207376;12533.310667912987;12596.874031259571;12633.178843375152;12640.6839829005;12386;12535.185212961129;12551.435185416496;12569.371531423209;12999.870001299865;10727.570897164025;11094.112471002461;11115.555377784798;11236.438178090899;11427.722478086202;9063.860377810373;9091.49660709382;9249.907500925174;9325.673601423918;9574.95212523933;9571.856422153813;9977.553254777871;10039.69880903574;10051.49742512886;10126.746831329276 04:27:35 INFO - PROCESS | 6333 | __end_tp_report 04:27:35 INFO - PROCESS | 6333 | __start_cc_report 04:27:35 INFO - PROCESS | 6333 | _x_x_mozilla_cycle_collect,996 04:27:35 INFO - PROCESS | 6333 | __end_cc_report 04:27:35 INFO - PROCESS | 6333 | __startTimestamp1469186855549__endTimestamp 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | ------- Summary: start ------- 04:27:35 INFO - PROCESS | 6333 | Number of tests: 6 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#0] dojo.html Cycles:165 Average:5398.82 Median:5585.44 stddev:627.28 (11.2%) stddev-sans-first:601.35 04:27:35 INFO - PROCESS | 6333 | Values: 3042.5 3072.8 3102.2 3105.4 3117.4 5297.9 5358.4 5365.0 5430.7 5483.6 5367.8 5398.8 5422.8 5464.3 5486.5 4841.7 4943.6 4949.1 4957.8 5094.5 4990.3 5021.7 5046.7 5055.1 5060.3 4819.1 4850.3 4893.5 4896.1 4964.0 4863.5 4868.7 4870.1 4923.0 5000.4 4865.9 4902.8 4923.8 4925.2 4941.0 5344.3 5386.7 5431.4 5455.8 5459.4 5858.5 5862.8 5892.7 5906.2 5909.9 5905.6 5937.5 5939.5 5956.5 5957.2 5320.6 5464.4 5530.4 5535.6 5543.8 5976.5 6027.2 6029.5 6031.0 6032.3 5805.9 5812.0 5812.7 5818.8 5824.4 5666.4 5734.4 5791.8 5795.5 5899.5 5791.0 5797.6 5802.3 5803.1 5849.0 5728.6 5743.7 5757.2 5789.1 5789.1 4342.3 4349.1 4351.5 4386.9 4413.7 3933.9 3936.6 3963.7 3972.4 3989.9 5489.6 5498.8 5504.3 5505.2 5506.1 5661.4 5774.5 5821.2 5837.4 5879.8 5503.4 5508.8 5517.8 5520.6 5562.5 5023.6 5027.0 5027.5 5029.7 5079.8 5792.2 5917.4 5980.7 5991.5 6004.9 5861.7 5862.5 5863.9 5902.8 5926.7 5984.8 5992.6 6001.2 6003.6 6004.1 5585.4 5634.2 5658.3 5690.4 5767.5 5754.9 5801.2 5808.0 5831.6 5850.3 5647.1 5648.9 5649.2 5654.2 5677.0 5467.5 5573.7 5650.6 5666.9 5673.6 5664.2 5664.9 5677.5 5695.4 5722.9 5652.6 5725.2 5756.6 5761.0 5772.7 5358.6 5464.6 5550.9 5571.5 5574.0 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#1] ext.html Cycles:180 Average:60052.62 Median:416274.00 stddev:117617.84 (28.3%) stddev-sans-first:117863.04 04:27:35 INFO - PROCESS | 6333 | Values: 585.2 612.7 630.4 634.7 648.9 30391.0 43550.6 44663.0 44707.3 44723.1 45667.1 46348.5 46361.0 46374.0 46403.3 8967.8 9101.2 9113.9 9117.5 9122.4 8990.0 9084.1 9091.6 9093.8 9098.1 9095.3 9134.9 9137.0 9143.1 9146.3 9054.0 9059.5 9084.2 9103.5 9116.0 8954.5 9006.1 9026.7 9026.7 9041.7 43235.0 43724.3 44015.1 44019.1 44039.8 3664.2 3708.9 3739.9 3838.5 4233.1 32931.8 33413.5 33902.3 34599.3 34637.5 4302.4 4365.1 4410.0 4468.3 4502.4 381727.0 385787.0 386758.0 386791.0 387372.0 50531.2 56964.7 57256.7 57267.0 57299.7 59105.0 60478.4 60507.7 65233.0 65251.7 16945.9 16978.5 16980.7 17137.6 17140.0 13425.0 13496.0 13500.4 13501.5 13502.5 16031.0 16042.0 16048.4 16100.6 16118.4 5396.8 6385.1 6407.4 6524.4 6586.5 976.3 1079.2 1106.4 1150.8 1157.7 32137.2 32570.5 32801.7 33005.3 33408.5 1059.1 1111.3 1161.4 1174.2 1202.9 16785.7 17103.7 17150.8 17342.0 17450.6 514257.0 519652.0 520096.0 520738.0 521104.0 58742.7 58853.4 58864.1 59005.4 59229.7 1358.8 1365.2 1401.1 1412.9 1413.9 31383.0 32022.5 33145.0 33327.2 33588.5 48751.3 49209.0 49265.0 49314.8 49340.3 415290.0 415576.0 416972.0 417645.0 418170.0 27247.0 27435.2 27592.9 27766.0 27893.6 34025.7 45863.5 47159.8 47243.8 47931.0 38900.4 40322.4 40410.8 40447.4 41720.6 38022.8 39855.4 39898.6 39988.8 40549.2 38998.2 39763.2 40028.6 40140.0 40543.0 33883.8 35935.8 36282.3 36510.8 36540.5 27080.2 27288.0 27634.4 27771.6 27827.9 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#2] jquery.html Cycles:180 Average:29414.43 Median:360.22 stddev:84365.98 (23421.0%) stddev-sans-first:84574.44 04:27:35 INFO - PROCESS | 6333 | Values: 359.5 361.0 362.9 373.8 380.5 18879.4 19033.2 19371.3 19512.0 19661.4 18059.5 18235.7 18599.0 18872.5 18904.3 5484.4 5691.9 5694.7 5695.6 5698.3 5606.1 5849.8 5906.8 5909.4 5942.7 5625.6 5905.7 5946.7 5951.3 5952.8 5818.4 5970.8 5971.8 5975.6 5984.2 5574.2 5702.3 5761.9 5785.4 5791.9 11769.3 11830.1 11849.5 11872.0 12726.2 2222.5 2241.7 2276.1 2338.9 2468.7 9709.6 10194.9 10223.7 11686.2 13981.9 1862.7 1976.4 1983.2 2010.2 2018.3 77948.6 78049.2 79330.2 79996.8 82216.6 9973.6 10090.2 10211.7 10509.9 10675.6 14328.7 14331.9 14357.7 14436.3 14453.9 7882.8 8095.9 8145.3 8269.0 8333.2 7658.4 7839.7 7849.2 8099.3 8232.8 4432.5 4438.5 4446.9 4487.4 4548.4 1764.7 1852.6 1877.2 1885.0 1890.2 913.6 1003.5 1011.7 1070.5 1080.0 19040.6 19674.8 19687.2 19700.7 19867.8 981.9 984.2 998.1 1018.8 1026.6 10512.4 10786.9 10797.9 10816.5 10849.0 493399.0 494534.0 507425.9 528029.0 529006.0 96250.5 96623.5 98522.5 99252.5 102180.0 981.5 1005.7 1008.0 1019.8 1027.6 16416.0 16792.4 17224.3 17260.8 17519.4 42753.6 43941.5 44203.8 44396.6 44772.8 62129.7 62431.0 62674.0 63757.4 63811.4 6636.4 7206.1 7612.9 8119.7 8325.4 11575.9 13178.5 13229.9 13286.9 13318.2 11296.9 11487.5 11775.4 11900.8 11905.6 10965.2 10975.7 11341.5 11447.9 11818.7 6292.3 7893.8 11769.6 11801.8 11841.1 7259.2 7400.2 7435.7 7713.5 7839.5 8288.1 8746.6 11426.9 11465.3 11498.6 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#3] mootools.html Cycles:180 Average:5607.83 Median:4937.47 stddev:3144.77 (63.7%) stddev-sans-first:3136.40 04:27:35 INFO - PROCESS | 6333 | Values: 1233.5 1272.7 1329.8 1367.4 1427.5 7599.2 8429.6 8631.4 8651.7 8907.0 8685.7 8718.8 8758.7 8768.9 8778.3 2260.6 2283.2 2285.4 2287.1 2287.3 2244.6 2280.4 2295.0 2298.1 2324.6 2247.2 2266.9 2272.3 2297.6 2307.7 2196.5 2204.5 2207.3 2212.6 2214.7 2233.5 2238.2 2239.4 2242.3 2246.2 8612.9 8613.8 8719.7 8720.6 8725.9 4823.9 4936.1 4938.8 4945.4 4981.4 3094.6 3158.6 3225.5 3225.6 3232.9 1140.5 1151.2 2110.9 2135.8 2190.9 11605.1 11626.4 11643.9 11664.4 11675.5 4025.3 4154.7 4224.4 4225.3 4259.7 9272.9 9273.3 9278.4 9280.6 9313.0 4929.0 4934.6 4941.3 4941.5 4943.2 4205.3 4488.4 4578.9 4612.8 4650.0 3460.7 3570.6 3608.4 3630.7 3641.0 1023.6 1034.5 1041.4 1046.7 1063.6 956.5 990.7 994.7 1024.3 1032.6 6059.0 6170.9 6186.2 6220.3 6333.1 417.0 427.5 436.2 436.5 438.2 4418.3 4429.9 4432.8 4497.1 4528.3 10009.2 10245.9 10274.9 10365.3 10373.6 10096.8 10260.6 10311.4 10319.6 10348.7 7664.5 7775.7 7833.5 7838.3 7843.1 5834.5 5896.9 5984.0 6000.7 6073.5 9337.4 9432.1 9447.1 9679.4 9760.3 9224.2 9787.8 9811.9 9895.8 9918.0 6663.0 7105.3 7114.0 7198.1 7344.2 8520.3 8582.1 8586.2 8677.0 8678.0 7651.2 7716.1 7841.5 7974.6 8010.2 7271.9 7412.3 7463.8 7509.7 7525.3 6923.9 7005.9 7011.6 7012.6 7023.9 5278.6 5289.3 5302.6 5307.8 5515.9 5373.4 5392.4 5400.4 5495.1 5568.6 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#4] prototype.html Cycles:180 Average:4559.22 Median:5074.25 stddev:1334.21 (26.3%) stddev-sans-first:1299.29 04:27:35 INFO - PROCESS | 6333 | Values: 310.9 314.3 321.9 324.1 325.1 5300.5 5376.5 5378.5 5435.8 5438.6 5050.8 5102.0 5221.6 5235.0 5324.4 4117.8 4118.2 4128.0 4136.3 4160.9 4152.1 4154.2 4178.5 4180.3 4184.8 3952.2 3962.8 4087.2 4125.5 4158.3 4098.5 4120.8 4124.9 4126.1 4148.6 3675.1 3677.7 3681.9 3692.8 3693.6 5136.9 5201.9 5202.7 5242.4 5262.5 4713.0 4716.7 4744.7 4753.5 4758.8 5485.3 5501.3 5504.6 5521.9 5530.9 3961.3 3986.6 3991.2 4049.9 4050.6 5662.6 5672.1 5673.6 5681.9 5697.2 4116.6 4130.5 4135.4 4138.1 4149.1 4026.0 4157.8 4192.9 4194.6 4197.3 4667.7 4679.2 4689.9 4695.2 4733.5 5495.4 5512.3 5512.9 5515.4 5523.4 1380.8 1392.2 1412.8 1426.5 1430.6 908.2 916.4 917.7 922.8 922.8 4749.2 4756.5 4782.6 4825.7 4863.5 4667.9 4975.4 5004.8 5042.0 5070.3 4737.0 4754.3 4757.3 4761.3 4764.4 2654.2 2659.4 2667.9 2671.5 2693.9 4938.7 5035.8 5094.2 5133.5 5136.6 5070.7 5077.8 5079.4 5092.5 5093.4 5122.9 5146.2 5189.7 5192.7 5275.8 4927.3 4968.6 5354.0 5694.9 5763.0 5616.7 5663.9 5713.3 5719.1 5731.6 5764.8 5936.3 5998.2 6011.2 6013.5 3952.4 3962.4 3964.4 3988.8 3989.8 5514.5 5634.4 5653.2 5668.2 5789.7 5447.2 5622.2 5624.8 5633.2 5690.2 5335.9 5597.9 5598.4 5635.3 5662.1 5580.6 5590.4 5606.6 5619.8 5635.1 5627.1 5676.8 5683.7 5732.7 5743.9 5468.3 5517.3 5530.1 5537.4 5634.9 04:27:35 INFO - PROCESS | 6333 | 04:27:35 INFO - PROCESS | 6333 | [#5] yui.html Cycles:180 Average:20065.52 Median:53218.47 stddev:28849.72 (54.2%) stddev-sans-first:28894.05 04:27:35 INFO - PROCESS | 6333 | Values: 711.0 736.5 748.1 784.8 829.6 15509.5 16502.7 16504.4 16510.8 16524.7 13821.4 13943.2 13945.4 14051.4 14079.5 658.2 790.7 803.2 816.0 821.9 801.7 812.5 812.9 813.0 813.0 850.9 852.3 854.3 854.5 855.0 682.9 827.6 849.7 852.6 853.5 622.9 623.1 623.2 623.4 623.5 15110.0 15213.5 15221.2 15222.1 15228.7 14048.6 14388.4 14512.6 14550.0 14600.4 12967.5 13169.7 13196.1 13239.2 13641.2 4430.0 4530.6 4559.2 4564.9 4613.2 67179.3 68188.3 68576.3 68784.0 76463.2 11245.2 11303.6 11423.7 11473.4 11750.5 22222.7 22293.3 22341.4 22434.8 22441.0 8484.7 8534.7 8672.4 8726.5 8811.5 4400.9 4549.9 4606.2 4610.8 4743.8 3511.1 3594.5 3598.0 3598.9 3605.4 626.3 630.3 631.8 649.2 651.5 627.6 658.3 664.3 681.5 711.9 9094.5 9408.2 9577.7 9593.4 10069.0 656.8 676.1 680.4 685.7 711.5 4429.5 4702.0 4767.2 4804.8 4840.0 110812.4 112186.0 112690.4 116181.0 117517.0 65051.7 68740.3 95199.0 99813.5 100982.0 81640.2 86059.6 88475.0 89361.0 90578.1 9190.2 9207.4 9291.5 9438.8 9458.0 52912.5 53524.5 53828.2 54545.7 54584.7 66412.3 73083.0 73363.6 74282.6 76250.6 11262.2 11431.8 11938.8 12206.7 12227.6 12944.9 13028.9 13042.7 13310.5 13541.4 12074.9 12533.3 12596.9 12633.2 12640.7 12386.0 12535.2 12551.4 12569.4 12999.9 10727.6 11094.1 11115.6 11236.4 11427.7 9063.9 9091.5 9249.9 9325.7 9575.0 9571.9 9977.6 10039.7 10051.5 10126.7 04:27:35 INFO - PROCESS | 6333 | -------- Summary: end -------- 04:27:35 INFO - PROCESS | 6333 | 04:27:36 INFO - TEST-INFO | 6333: exit 0 04:27:36 INFO - TEST-OK | dromaeo_css | took 1113003ms 04:27:36 INFO - TEST-START | kraken 04:27:36 INFO - operating with platform_type : linux_ 04:27:36 INFO - Initialising browser for kraken test... 04:27:36 INFO - TEST-INFO | started process 6525 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplVwbe5/profile http://localhost:48631/getInfo.html) 04:27:39 INFO - PROCESS | 6525 | JavaScript error: , line 0: uncaught exception: undefined 04:27:39 INFO - PROCESS | 6525 | __metrics Screen width/height:1600/1200 04:27:39 INFO - PROCESS | 6525 | colorDepth:24 04:27:39 INFO - PROCESS | 6525 | Browser inner width/height: 1024/697 04:27:39 INFO - PROCESS | 6525 | __metrics 04:27:40 INFO - PROCESS | 6525 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:27:40 INFO - PROCESS | 6525 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:27:41 INFO - TEST-INFO | 6525: exit 0 04:27:41 INFO - Browser initialized. 04:27:41 INFO - Running cycle 1/1 for kraken test... 04:27:41 INFO - TEST-INFO | started process 6605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplVwbe5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:27:53 INFO - PROCESS | 6605 | RSS: Main: 158625792 04:27:53 INFO - PROCESS | 6605 | 04:27:55 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-beat-detection) 04:27:56 INFO - PROCESS | 6605 | RSS: Main: 214405120 04:27:56 INFO - PROCESS | 6605 | 04:27:59 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-dft) 04:27:59 INFO - PROCESS | 6605 | RSS: Main: 188043264 04:27:59 INFO - PROCESS | 6605 | 04:28:03 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-fft) 04:28:03 INFO - PROCESS | 6605 | RSS: Main: 271278080 04:28:03 INFO - PROCESS | 6605 | 04:28:05 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-oscillator) 04:28:06 INFO - PROCESS | 6605 | RSS: Main: 181493760 04:28:06 INFO - PROCESS | 6605 | 04:28:08 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:08 INFO - PROCESS | 6605 | RSS: Main: 186322944 04:28:08 INFO - PROCESS | 6605 | 04:28:12 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:12 INFO - PROCESS | 6605 | RSS: Main: 220368896 04:28:12 INFO - PROCESS | 6605 | 04:28:15 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:16 INFO - PROCESS | 6605 | RSS: Main: 221478912 04:28:16 INFO - PROCESS | 6605 | 04:28:19 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48631/tests/kraken/driver.html?testName=json-parse-financial) 04:28:19 INFO - PROCESS | 6605 | RSS: Main: 221429760 04:28:19 INFO - PROCESS | 6605 | 04:28:21 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48631/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:28:22 INFO - PROCESS | 6605 | RSS: Main: 185524224 04:28:22 INFO - PROCESS | 6605 | 04:28:23 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:28:24 INFO - PROCESS | 6605 | RSS: Main: 190648320 04:28:24 INFO - PROCESS | 6605 | 04:28:26 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:28:27 INFO - PROCESS | 6605 | RSS: Main: 195764224 04:28:27 INFO - PROCESS | 6605 | 04:28:29 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:28:30 INFO - PROCESS | 6605 | RSS: Main: 189345792 04:28:30 INFO - PROCESS | 6605 | 04:28:32 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:28:33 INFO - PROCESS | 6605 | RSS: Main: 194154496 04:28:33 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48631/tests/kraken/driver.html?testName=ai-astar) 04:28:35 INFO - PROCESS | 6605 | RSS: Main: 244965376 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | __start_tp_report 04:28:35 INFO - PROCESS | 6605 | _x_x_mozilla_page_load 04:28:35 INFO - PROCESS | 6605 | _x_x_mozilla_page_load_details 04:28:35 INFO - PROCESS | 6605 | |i|pagename|runs| 04:28:35 INFO - PROCESS | 6605 | |0;ai-astar;102;112;111;111;113;112;111;111;111;111 04:28:35 INFO - PROCESS | 6605 | |1;audio-beat-detection;146;117;119;182;124;122;152;118;162;117 04:28:35 INFO - PROCESS | 6605 | |2;audio-dft;161;161;160;159;159;160;161;161;160;161 04:28:35 INFO - PROCESS | 6605 | |3;audio-fft;76;75;74;108;76;75;74;73;99;75 04:28:35 INFO - PROCESS | 6605 | |4;audio-oscillator;83;83;114;84;84;84;118;83;83;134 04:28:35 INFO - PROCESS | 6605 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;117;117;117 04:28:35 INFO - PROCESS | 6605 | |6;imaging-darkroom;147;148;148;146;147;146;146;145;145;146 04:28:35 INFO - PROCESS | 6605 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;96;95 04:28:35 INFO - PROCESS | 6605 | |8;json-parse-financial;51;51;52;51;61;51;53;51;49;50 04:28:35 INFO - PROCESS | 6605 | |9;json-stringify-tinderbox;60;62;61;62;62;61;61;62;65;61 04:28:35 INFO - PROCESS | 6605 | |10;stanford-crypto-aes;76;82;76;76;78;76;78;75;73;78 04:28:35 INFO - PROCESS | 6605 | |11;stanford-crypto-ccm;127;118;119;119;118;118;126;117;198;119 04:28:35 INFO - PROCESS | 6605 | |12;stanford-crypto-pbkdf2;155;156;155;154;154;156;159;159;156;154 04:28:35 INFO - PROCESS | 6605 | |13;stanford-crypto-sha256-iterative;65;63;64;65;67;66;64;61;61;66 04:28:35 INFO - PROCESS | 6605 | __end_tp_report 04:28:35 INFO - PROCESS | 6605 | __start_cc_report 04:28:35 INFO - PROCESS | 6605 | _x_x_mozilla_cycle_collect,1687 04:28:35 INFO - PROCESS | 6605 | __end_cc_report 04:28:35 INFO - PROCESS | 6605 | __startTimestamp1469186915453__endTimestamp 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | ------- Summary: start ------- 04:28:35 INFO - PROCESS | 6605 | Number of tests: 14 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:0.73 04:28:35 INFO - PROCESS | 6605 | Values: 102.0 112.0 111.0 111.0 113.0 112.0 111.0 111.0 111.0 111.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:135.00 stddev:23.15 (17.1%) stddev-sans-first:24.26 04:28:35 INFO - PROCESS | 6605 | Values: 146.0 117.0 119.0 182.0 124.0 122.0 152.0 118.0 162.0 117.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 04:28:35 INFO - PROCESS | 6605 | Values: 161.0 161.0 160.0 159.0 159.0 160.0 161.0 161.0 160.0 161.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:12.34 (16.5%) stddev-sans-first:12.98 04:28:35 INFO - PROCESS | 6605 | Values: 76.0 75.0 74.0 108.0 76.0 75.0 74.0 73.0 99.0 75.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.00 stddev:19.29 (23.2%) stddev-sans-first:19.97 04:28:35 INFO - PROCESS | 6605 | Values: 83.0 83.0 114.0 84.0 84.0 84.0 118.0 83.0 83.0 134.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:28:35 INFO - PROCESS | 6605 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:1.07 (0.7%) stddev-sans-first:1.12 04:28:35 INFO - PROCESS | 6605 | Values: 147.0 148.0 148.0 146.0 147.0 146.0 146.0 145.0 145.0 146.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 04:28:35 INFO - PROCESS | 6605 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:51.00 stddev:3.33 (6.5%) stddev-sans-first:3.52 04:28:35 INFO - PROCESS | 6605 | Values: 51.0 51.0 52.0 51.0 61.0 51.0 53.0 51.0 49.0 50.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:1.34 (2.2%) stddev-sans-first:1.27 04:28:35 INFO - PROCESS | 6605 | Values: 60.0 62.0 61.0 62.0 62.0 61.0 61.0 62.0 65.0 61.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:77.00 stddev:2.39 (3.1%) stddev-sans-first:2.52 04:28:35 INFO - PROCESS | 6605 | Values: 76.0 82.0 76.0 76.0 78.0 76.0 78.0 75.0 73.0 78.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:119.00 stddev:24.88 (20.9%) stddev-sans-first:26.38 04:28:35 INFO - PROCESS | 6605 | Values: 127.0 118.0 119.0 119.0 118.0 118.0 126.0 117.0 198.0 119.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:156.00 stddev:1.87 (1.2%) stddev-sans-first:1.96 04:28:35 INFO - PROCESS | 6605 | Values: 155.0 156.0 155.0 154.0 154.0 156.0 159.0 159.0 156.0 154.0 04:28:35 INFO - PROCESS | 6605 | 04:28:35 INFO - PROCESS | 6605 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:65.00 stddev:2.04 (3.1%) stddev-sans-first:2.15 04:28:35 INFO - PROCESS | 6605 | Values: 65.0 63.0 64.0 65.0 67.0 66.0 64.0 61.0 61.0 66.0 04:28:35 INFO - PROCESS | 6605 | -------- Summary: end -------- 04:28:35 INFO - PROCESS | 6605 | 04:28:36 INFO - TEST-INFO | 6605: exit 0 04:28:36 INFO - TEST-OK | kraken | took 60117ms 04:28:36 INFO - SUITE-END | took 1173s 04:28:36 INFO - Completed test suite (00:19:33) 04:28:36 INFO - javascript benchmark 04:28:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3042.513197888338, 3072.8156310621353, 3102.177922850444, 3105.363400502897, 3117.392108538836, 5297.947020529794, 5358.437364076774, 5365.0, 5430.72846357682, 5483.5613150947975, 5367.812126575571, 5398.838034858951, 5422.783088676458, 5464.289642346489, 5486.478784515474, 4841.661083724141, 4943.629227807911, 4949.109160351135, 4957.801687932479, 5094.541491265785, 4990.251462280668, 5021.698698078127, 5046.697198168104, 5055.140626093555, 5060.291559181717, 4819.084373968934, 4850.345203397532, 4893.486183950669, 4896.069746748107, 4964.032013757319, 4863.513648635144, 4868.683535570212, 4870.14772414826, 4922.975385123087, 5000.399952005746, 4865.927011094836, 4902.828401005983, 4923.753812309406, 4925.236588328779, 4941.036497882914, 5344.278522399504, 5386.67679939201, 5431.429699881495, 5455.809046683386, 5459.426760190163, 5858.53131749459, 5862.794802181903, 5892.734826932756, 5906.232189815297, 5909.940900590982, 5905.557083218776, 5937.495312898409, 5939.524838012949, 5956.493698035672, 5957.225560677128, 5320.574354051667, 5464.426235245322, 5530.446955304518, 5535.612507124503, 5543.77824887004, 5976.5218782497295, 6027.186329845415, 6029.457348838538, 6031.0, 6032.336442991267, 5805.8548536286935, 5812.041013232853, 5812.738426770806, 5818.796342128006, 5824.446677565625, 5666.405027472056, 5734.369219385864, 5791.797287094932, 5795.478406943395, 5899.4985426239255, 5791.0, 5797.623154495029, 5802.3037235531165, 5803.100519419497, 5849.034909239927, 5728.6276392035215, 5743.712814359349, 5757.222774925415, 5789.102689083199, 5789.102689083199, 4342.348647702806, 4349.108432771274, 4351.477822661301, 4386.890327741832, 4413.691041627056, 3933.8819835404984, 3936.6063393660406, 3963.6829053675638, 3972.3842804365368, 3989.9202015959518, 5489.64317319373, 5498.835034948958, 5504.284443022461, 5505.174223866452, 5506.091494903376, 5661.377248502661, 5774.451427114417, 5821.214136091573, 5837.357890632027, 5879.823605291848, 5503.394626591072, 5508.834734957958, 5517.806876759374, 5520.613557051048, 5562.499375056264, 5023.623228257895, 5027.0, 5027.547520723152, 5029.748512574357, 5079.822206222764, 5792.218050563204, 5917.437843404869, 5980.730867110903, 5991.520678345809, 6004.939950600526, 5861.706914654165, 5862.530997520103, 5863.941360586339, 5902.793402230901, 5926.674032928058, 5984.820455386345, 5992.610480318765, 6001.219841420588, 6003.579749417673, 6004.099385092097, 5585.441455854382, 5634.183043458757, 5658.292713410824, 5690.402507736793, 5767.452092051249, 5754.856128596819, 5801.245838041028, 5808.01263785166, 5831.591788574929, 5850.268716410449, 5647.096464565814, 5648.85877853057, 5649.152627105967, 5654.208410822406, 5677.034904066236, 5467.535259502828, 5573.693446860461, 5650.63270887391, 5666.943330566806, 5673.602847800779, 5664.235328230801, 5664.886702266014, 5677.51741102011, 5695.401982791747, 5722.856928576819, 5652.63257888236, 5725.169850371756, 5756.597038207453, 5761.049426844524, 5772.682502462237, 5358.571314294769, 5464.590155738256, 5550.916736249041, 5571.498565129158, 5573.996680597369], "value": 5355.138879345696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.2070444547638, 612.7395856760877, 630.3664816859056, 634.7016902056032, 648.8961766117422, 30391.0, 43550.56449435505, 44663.0, 44707.329390059174, 44723.10553788922, 45667.08665826682, 46348.53651463484, 46361.0, 46374.0, 46403.303950440684, 8967.775805604846, 9101.180893719564, 9113.863292050624, 9117.49853758043, 9122.407043542182, 8989.95505022474, 9084.09159084093, 9091.63633454661, 9093.772655683595, 9098.090190980914, 9095.272378209727, 9134.908650913472, 9136.954315228415, 9143.131402516783, 9146.31402644801, 9054.049324820899, 9059.547022648841, 9084.182423581877, 9103.499307538083, 9116.0, 8954.507502087383, 9006.054364291786, 9026.729198124067, 9026.729198124067, 9041.683541076096, 43235.0, 43724.344134838, 44015.11969760619, 44019.119617607466, 44039.77980110111, 3664.157243833933, 3708.8887333380044, 3739.925201495982, 3838.500994870677, 4233.111046680201, 32931.83534082338, 33413.498797518056, 33902.32195356079, 34599.308013839836, 34637.4804377932, 4302.354646802974, 4365.083332500179, 4410.0, 4468.329750537413, 4502.41468609082, 381727.0, 385787.0, 386758.0, 386790.9999999972, 387372.0, 50531.24203136956, 56964.71517642385, 57256.71371643115, 57267.0, 57299.71350143223, 59105.0, 60478.39521604816, 60507.69746151241, 65233.0, 65251.67374163099, 16945.915270423568, 16978.49064528066, 16980.74528882068, 17137.571560710832, 17140.0, 13425.0, 13496.0, 13500.392482338319, 13501.459941602423, 13502.459901604023, 16031.0, 16042.037477751372, 16048.358065677243, 16100.597485062966, 16118.355265789238, 5396.811111611075, 6385.13800636908, 6407.359264073555, 6524.380183882524, 6586.5389422739945, 976.3360914578155, 1079.2337440417234, 1106.435717783936, 1150.8216226484908, 1157.6584907452323, 32137.196570085467, 32570.511442328385, 32801.671983280336, 33005.33989320248, 33408.49887251693, 1059.1473863539925, 1111.3054341036852, 1161.4483120517687, 1174.236746115032, 1202.8676845546984, 16785.664286714196, 17103.74344384835, 17150.828491715172, 17342.007990514958, 17450.563735906704, 514257.0, 519652.0, 520096.0, 520738.0, 521104.0, 58742.706286468296, 58853.41146588565, 58864.11703824446, 59005.40994590085, 59229.70385148133, 1358.7554240236852, 1365.180891465113, 1401.1453013661658, 1412.9222892740997, 1413.9293035348192, 31383.0, 32022.519662205552, 33145.0, 33327.166820829676, 33588.49617255792, 48751.26873096977, 49209.0, 49265.0, 49314.75342623264, 49340.25989610087, 415290.0, 415576.0, 416972.0, 417645.0, 418170.0, 27247.0, 27435.17694469169, 27592.862035689694, 27766.0, 27893.581596275668, 34025.65974340225, 45863.54136458593, 47159.764201178776, 47243.763781180875, 47931.0, 38900.41649375318, 40322.395164071975, 40410.79794601008, 40447.393289101274, 41720.582794172886, 38022.809885950395, 39855.40216896691, 39898.601013989486, 39988.800055999534, 40549.1890162201, 38998.2200355997, 39763.204735905696, 40028.59971400365, 40139.982502187224, 40543.0, 33883.83058084694, 35935.83636172759, 36282.274354512236, 36510.8174459126, 36540.45189322109, 27080.1875943722, 27288.0, 27634.447311053264, 27771.583426248217, 27827.860860695568], "value": 20144.402756690804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.4787558040841, 360.95127157833684, 362.8875048734893, 373.84111752505197, 380.4616467698204, 18879.43361699148, 19033.238670453182, 19371.32200372987, 19512.024398780075, 19661.410157695293, 18059.458216253533, 18235.72646410305, 18599.0, 18872.528186795338, 18904.338348157817, 5484.369297530785, 5691.9146212806845, 5694.6867922264255, 5695.629784064044, 5698.34469036062, 5606.131049687285, 5849.7660093596205, 5906.793262235823, 5909.4090590941, 5942.732577034044, 5625.634333768292, 5905.7342419591005, 5946.702664866761, 5951.315598706139, 5952.7916522921705, 5818.359980402152, 5970.820875373745, 5971.820845374646, 5975.641461512279, 5984.162217289583, 5574.191742197399, 5702.287214098238, 5761.9135712964335, 5785.363610002896, 5791.884162316772, 11769.293842369485, 11830.053595712323, 11849.466773995191, 11872.0, 12726.17279876805, 2222.488827569668, 2241.7421996470453, 2276.066812606823, 2338.918137865184, 2468.7037555493275, 9709.563069661883, 10194.898051019543, 10223.693289201336, 11686.24039437434, 13981.860181398157, 1862.6647203503364, 1976.3873199308262, 1983.1769815526538, 2010.1858747207405, 2018.2835093541728, 77948.61025694892, 78049.21950780477, 79330.20669793285, 79996.80004799932, 82216.58891705563, 9973.601055957826, 10090.192784577217, 10211.693649190536, 10509.894901050891, 10675.558799561959, 14328.713425731636, 14331.928340358232, 14357.712845743026, 14436.278186090654, 14453.927730361282, 7882.802929926797, 8095.919040809635, 8145.3483721302155, 8269.0, 8333.208345207195, 7658.425618078666, 7839.686412543549, 7849.233922367536, 8099.271065604124, 8232.794180145425, 4432.512423633398, 4438.489573699066, 4446.933296000562, 4487.371767952489, 4548.363229147922, 1764.7441121037377, 1852.638735446602, 1877.2209533043633, 1884.9623007539772, 1890.1966664167674, 913.5569248914271, 1003.4982508745627, 1011.7217765114652, 1070.539667942792, 1079.994600026995, 19040.62065724891, 19674.803251967296, 19687.21251149938, 19700.70448943267, 19867.801321986884, 981.8625392445064, 984.2421335571713, 998.1415982255255, 1018.8217062014118, 1026.6355443817486, 10512.369257844553, 10786.892131078746, 10797.946010269898, 10816.459177041117, 10849.0, 493399.0, 494534.0, 507425.9430542724, 528029.0, 529006.0, 96250.51874740582, 96623.51688241513, 98522.50738746404, 99252.5037374823, 102180.0, 981.5239608789731, 1005.6731562242301, 1007.9718686939281, 1019.8164330420448, 1027.6249169053242, 16416.0, 16792.412265571136, 17224.311027558757, 17260.827391726423, 17519.386821461187, 42753.572464274956, 43941.462048828136, 44203.778981106174, 44396.55603443924, 44772.77613611911, 62129.68935155295, 62431.0, 62674.0, 63757.36242637514, 63811.36188638054, 6636.402723754885, 7206.135263768381, 7612.923870761221, 8119.715809946623, 8325.417220794729, 11575.884241157817, 13178.538751143664, 13229.867701322864, 13286.933565332112, 13318.200907945555, 11296.943515282372, 11487.540498380306, 11775.411229438665, 11900.821487677866, 11905.583304584297, 10965.232433729563, 10975.670729878117, 11341.546338146381, 11447.885521144683, 11818.704532386759, 6292.339304372975, 7893.763187104396, 11769.646910592695, 11801.763964720829, 11841.111916606116, 7259.2377800330205, 7400.185979542139, 7435.7025718970635, 7713.460057796125, 7839.549683308606, 8288.129746376544, 8746.562671866508, 11426.885731142582, 11465.254758440675, 11498.597549086076], "value": 8931.755031762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.46960806853, 1272.694553307206, 1329.8470675872277, 1367.3778430813982, 1427.5217802036318, 7599.164091949881, 8429.578521073945, 8631.395802293842, 8651.653933842646, 8907.0, 8685.696000639979, 8718.782030449242, 8758.693445729401, 8768.868466972985, 8778.253848422892, 2260.6156953317895, 2283.235116236063, 2285.371522831218, 2287.0965968442456, 2287.325239054483, 2244.607193741097, 2280.395695140789, 2295.0360848443693, 2298.1267118495034, 2324.639680849471, 2247.2471721973075, 2266.8526545774553, 2272.261515007621, 2297.5864344417996, 2307.676925230469, 2196.5497073099978, 2204.459907322708, 2207.3267653365715, 2212.6127927612683, 2214.734231892175, 2233.452804062999, 2238.2054370698493, 2239.4177513846353, 2242.316093591454, 2246.225052356934, 8612.8708069379, 8613.827723445496, 8719.738407847775, 8720.563971801384, 8725.912740872573, 4823.903521929542, 4936.111499930011, 4938.827141050082, 4945.431275403339, 4981.402231732179, 3094.6441159266747, 3158.5735925649974, 3225.532297816827, 3225.629052658951, 3232.870685172614, 1140.5437824870035, 1151.153901882116, 2110.883901385423, 2135.7864213578673, 2190.9014094365793, 11605.129615278804, 11626.360550169737, 11643.941780291128, 11664.416779161009, 11675.532978680927, 4025.255327764373, 4154.688398370135, 4224.408582798411, 4225.260579398593, 4259.659227261812, 9272.907270927339, 9273.304502162366, 9278.443293402417, 9280.582373793195, 9313.022132675971, 4929.0388374267395, 4934.55588996992, 4941.308216849644, 4941.505849415102, 4943.184374578154, 4205.306124489425, 4488.371627972087, 4578.88552786183, 4612.815487380534, 4649.976750116228, 3460.653934606519, 3570.589382221078, 3608.440691692792, 3630.7458477906293, 3640.9635903640624, 1023.6007956896816, 1034.539629864718, 1041.4063983529468, 1046.7487802927242, 1063.5852017713098, 956.4929252164962, 990.7325022243954, 994.6518718448485, 1024.3341827811832, 1032.6282538286212, 6059.0, 6170.907436388457, 6186.1648677428875, 6220.284667263232, 6333.113364129025, 416.95205051418867, 427.5169058963352, 436.18651215482896, 436.5198281889959, 438.2068456094479, 4418.293073108352, 4429.911401772011, 4432.800523976429, 4497.123061003138, 4528.252838281646, 10009.249306302056, 10245.897541024495, 10274.948625256975, 10365.274430789772, 10373.636922707668, 10096.798064038678, 10260.640877569249, 10311.432871192006, 10319.638812641522, 10348.741281468023, 7664.540127592362, 7775.727849525239, 7833.52998820084, 7838.294553490213, 7843.098043725045, 5834.474897259267, 5896.941030589811, 5983.970080149746, 6000.729967151401, 6073.453389194994, 9337.439753614804, 9432.103950125002, 9447.055294470498, 9679.370840895319, 9760.316777825485, 9224.24739299531, 9787.755306117404, 9811.901880981099, 9895.802083958424, 9917.950410247902, 6662.967240077795, 7105.32499412555, 7114.0, 7198.064251647253, 7344.22885597026, 8520.318374530147, 8582.0988796178, 8586.184312490303, 8677.0, 8678.0, 7651.158372579123, 7716.112647045437, 7841.451098423284, 7974.601269936364, 8010.158933311918, 7271.8909216360735, 7412.332890039922, 7463.813404664927, 7509.662065207188, 7525.3227209551405, 6923.9307606923285, 7005.894911576229, 7011.6494175292055, 7012.649367531501, 7023.894641580483, 5278.60410469224, 5289.338832645919, 5302.575793936553, 5307.814226502054, 5515.924394742987, 5373.408925018323, 5392.379876314118, 5400.351957765093, 5495.065838807493, 5568.55451563882], "value": 4439.364002698314, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.89012226351906, 314.306953168264, 321.8873394311991, 324.1377934693715, 325.0929905563478, 5300.469953004697, 5376.462353764627, 5378.489043540867, 5435.755391007404, 5438.646487978283, 5050.752415175249, 5102.015321037188, 5221.634485586011, 5234.973825130878, 5324.387695415028, 4117.773522456264, 4118.19695159444, 4127.958720412803, 4136.31750761124, 4160.937585936198, 4152.1072969311745, 4154.169166166779, 4178.456800615916, 4180.28935081037, 4184.832606695729, 3952.2095580883793, 3962.8216730247063, 4087.1825634872994, 4125.484314460692, 4158.334666453368, 4098.479435147393, 4120.814563344657, 4124.917501649965, 4126.133511962492, 4148.647364973981, 3675.117971686803, 3677.669009789132, 3681.9197153608393, 3692.7968961707097, 3693.630636936312, 5136.948630513722, 5201.895962080737, 5202.661826981234, 5242.423333433319, 5262.500062494057, 4712.952870471286, 4716.716996980193, 4744.667873248874, 4753.500882407332, 4758.834730197752, 5485.286912701324, 5501.284832971728, 5504.614676972614, 5521.917171242435, 5530.861728456781, 3961.306771315037, 3986.60133986599, 3991.1818077294083, 4049.9392509112386, 4050.615191556827, 5662.6036177467595, 5672.078879377124, 5673.631213971079, 5681.886362272731, 5697.1739097831, 4116.58834116586, 4130.483689538808, 4135.359019352005, 4138.110306284103, 4149.087200815816, 4025.9798701006307, 4157.750534967911, 4192.874213773592, 4194.622483976456, 4197.349410841324, 4667.743274119888, 4679.157751604744, 4689.850986508327, 4695.2252878275385, 4733.502982186821, 5495.395506494282, 5512.283403157564, 5512.889742205133, 5515.393306736256, 5523.447655234525, 1380.7652699041187, 1392.2412285304533, 1412.7668934625876, 1426.4935947738404, 1430.577979496051, 908.182635627927, 916.4043371808244, 917.7338571814099, 922.7957515442278, 922.8292765838341, 4749.1926372516755, 4756.453007904066, 4782.593479554207, 4825.710457372569, 4863.537963893425, 4667.883302917454, 4975.378077740283, 5004.799808007711, 5041.974790126026, 5070.340855688737, 4737.010455608653, 4754.286856971413, 4757.286407038902, 4761.262004389325, 4764.356811830358, 2654.1772050664354, 2659.404357385592, 2667.852823286006, 2671.49241644087, 2693.8922443102056, 4938.744633401145, 5035.79856805731, 5094.235864620263, 5133.537981581676, 5136.640435169577, 5070.670406423571, 5077.822276220389, 5079.4412614612365, 5092.490750924878, 5093.388793344822, 5122.872967947045, 5146.202338637592, 5189.728516513478, 5192.688438693691, 5275.762590683428, 4927.334809800631, 4968.627352948615, 5353.9732301338245, 5694.886102278014, 5763.011760942468, 5616.719164041863, 5663.858403539944, 5713.314402271754, 5719.084946408555, 5731.627444216113, 5764.769409223752, 5936.257967754031, 5998.190244316961, 6011.248593925759, 6013.5189184865985, 3952.446657468014, 3962.445257663872, 3964.3657014878636, 3988.760674359548, 3989.840406383712, 5514.503694667499, 5634.352049514358, 5653.2085508028085, 5668.242844718151, 5789.739461724317, 5447.155690867763, 5622.18478320633, 5624.775008999758, 5633.155026745857, 5690.203371528072, 5335.893282134413, 5597.893169291083, 5598.384177740364, 5635.295058371922, 5662.065759149694, 5580.637258578179, 5590.357108932362, 5606.635568688022, 5619.831405057855, 5635.098384258663, 5627.127795191752, 5676.77292908279, 5683.687397193194, 5732.684702341411, 5743.8564035899435, 5468.316460442445, 5517.282753242052, 5530.11518157109, 5537.390887002507, 5634.88730225385], "value": 4131.929510667967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.978670639881, 736.5212611802327, 748.1022772672791, 784.7645706288113, 829.6473998550614, 15509.534713958572, 16502.66994660106, 16504.42234521792, 16510.75233871493, 16524.669506609862, 13821.37803798829, 13943.16341019537, 13945.442182312696, 14051.367688454018, 14079.5072172474, 658.2200092889917, 790.7351037402477, 803.2008151888864, 816.0330008939396, 821.9383546234056, 801.6673080671515, 812.4800127918137, 812.9430939834214, 812.979675508114, 812.9918700812975, 850.8596081646521, 852.2585350744861, 854.3293514591047, 854.4531499840107, 855.0, 682.9419499342537, 827.5903427803275, 849.7110982266029, 852.6205838401913, 853.5262929074361, 622.9065640153968, 623.1027320658237, 623.2365352443267, 623.3828509775335, 623.4887392338285, 15110.0, 15213.543593692208, 15221.162836044014, 15222.086674799548, 15228.695426091415, 14048.648783780385, 14388.424463021467, 14512.56462306122, 14550.0, 14600.415983360654, 12967.546135885294, 13169.736605267937, 13196.14225075367, 13239.20564766117, 13641.181529108286, 4429.955700442986, 4530.569595888385, 4559.247724125516, 4564.863054108382, 4613.238815595423, 67179.3282067178, 68188.31811681869, 68576.31423685749, 68784.0, 76463.23536764616, 11245.15661325404, 11303.60437384704, 11423.657290281304, 11473.36896470702, 11750.529978800923, 22222.66666000011, 22293.331200064025, 22341.441463963205, 22434.77565224327, 22441.0, 8484.660613575388, 8534.70128545498, 8672.436291641023, 8726.476411415335, 8811.515366654896, 4400.867973960786, 4549.909001820011, 4606.170889239969, 4610.815567377334, 4743.786529606176, 3511.1046683096156, 3594.514740509998, 3597.982010089933, 3598.9280214395567, 3605.387084195693, 626.2547568393597, 630.2657404124236, 631.7978246960928, 649.1528555235369, 651.5211319680031, 627.626562195493, 658.3087757854233, 664.3323459922747, 681.5297444763098, 711.850511392603, 9094.454332740057, 9408.153266205938, 9577.664781732607, 9593.42439453635, 10069.044879171526, 656.8456412743002, 676.1210426445601, 680.4114441008591, 685.6605980039913, 711.494838664554, 4429.512753597101, 4701.95298047022, 4767.237242041326, 4804.8318308859025, 4839.975800120977, 110812.4459377698, 112186.0, 112690.43654781673, 116181.0, 117517.0, 65051.674741626935, 68740.3125968734, 95199.0, 99813.50093249488, 100982.0, 81640.18359816444, 86059.56970215109, 88475.0, 89361.0, 90578.09421905829, 9190.172884440433, 9207.40151890125, 9291.48896810682, 9438.764030899283, 9457.959624441444, 52912.47087529075, 53524.464755351946, 53828.19257711059, 54545.72727136339, 54584.72707636436, 66412.33587664062, 73083.0, 73363.63318183375, 74282.62858685672, 76250.61874690591, 11262.211645184758, 11431.828522572001, 11938.761224775282, 12206.6948326289, 12227.572034979088, 12944.935275323563, 13028.869711303145, 13042.673933151747, 13310.534131305358, 13541.390637421537, 12074.879251207376, 12533.310667912987, 12596.874031259571, 12633.178843375152, 12640.6839829005, 12386.0, 12535.185212961129, 12551.435185416496, 12569.371531423209, 12999.870001299865, 10727.570897164025, 11094.112471002461, 11115.555377784798, 11236.438178090899, 11427.722478086202, 9063.860377810373, 9091.49660709382, 9249.907500925174, 9325.673601423918, 9574.95212523933, 9571.856422153813, 9977.553254777871, 10039.69880903574, 10051.49742512886, 10126.746831329276], "value": 7362.773608429911, "unit": "score"}], "name": "dromaeo_css", "value": 7118.754759563669, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 111.0, 113.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 119.0, 182.0, 124.0, 122.0, 152.0, 118.0, 162.0, 117.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 159.0, 159.0, 160.0, 161.0, 161.0, 160.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 108.0, 76.0, 75.0, 74.0, 73.0, 99.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 114.0, 84.0, 84.0, 84.0, 118.0, 83.0, 83.0, 134.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 61.0, 51.0, 53.0, 51.0, 49.0, 50.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 62.0, 62.0, 61.0, 61.0, 62.0, 65.0, 61.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 76.0, 76.0, 78.0, 76.0, 78.0, 75.0, 73.0, 78.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 118.0, 119.0, 119.0, 118.0, 118.0, 126.0, 117.0, 198.0, 119.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 155.0, 154.0, 154.0, 156.0, 159.0, 159.0, 156.0, 154.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 65.0, 67.0, 66.0, 64.0, 61.0, 61.0, 66.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1478.9, "alertThreshold": 2.0}]} 04:28:36 INFO - Return code: 0 04:28:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:28:36 INFO - # TBPL SUCCESS # 04:28:36 INFO - Running post-action listener: _resource_record_post_action 04:28:36 INFO - [mozharness: 2016-07-22 11:28:36.748596Z] Finished run-tests step (success) 04:28:36 INFO - Running post-run listener: _resource_record_post_run 04:28:36 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 532480; Write bytes: 300716032; Read time: 84; Write time: 182948 04:28:36 INFO - TinderboxPrint: CPU usage
13.0% 04:28:36 INFO - TinderboxPrint: I/O read bytes / time
532,480 / 84 04:28:36 INFO - TinderboxPrint: I/O write bytes / time
300,716,032 / 182,948 04:28:36 INFO - TinderboxPrint: CPU idle
8,267.8 (87.0%) 04:28:36 INFO - TinderboxPrint: CPU user
1,197.5 (12.6%) 04:28:36 INFO - TinderboxPrint: Swap in / out
0 / 0 04:28:36 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 5214208; Read time: 0; Write time: 352 04:28:36 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 528384; Write bytes: 295501824; Read time: 76; Write time: 182596 04:28:37 INFO - Running post-run listener: _upload_blobber_files 04:28:37 INFO - Blob upload gear active. 04:28:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:28:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:28:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:28:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:28:37 INFO - (blobuploader) - INFO - Open directory for files ... 04:28:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:28:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:28:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:38 INFO - (blobuploader) - INFO - Done attempting. 04:28:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:28:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:54 INFO - (blobuploader) - INFO - Done attempting. 04:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:28:55 INFO - (blobuploader) - INFO - Done attempting. 04:28:55 INFO - (blobuploader) - INFO - Iteration through files over. 04:28:55 INFO - Return code: 0 04:28:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:28:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:28:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad8c1096e36cee85478f68c6eb11d245456e87bed19bb444f57747878ee53da9e23cbf26fbef5d1ac512ec72477daa4c6bc5fcea17a5f92419b490f5c67bacc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/86165f7a3f938c1d46a06a96aeb1764b04257234c2999743bd180490afcf51bdf88c5a22bcff2d67b7340d21d4da731dd35a687c61415adba45c5ca2b83729ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5e42fbb02c5862d8d18e519355b9062b8dfa6bef61b29583a9a228a45bfb09c83b27031902da02d1043fdc4916038b50b84b62666b9299bdc0b12c3b5fbf3630"} 04:28:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:28:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:28:55 INFO - Contents: 04:28:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad8c1096e36cee85478f68c6eb11d245456e87bed19bb444f57747878ee53da9e23cbf26fbef5d1ac512ec72477daa4c6bc5fcea17a5f92419b490f5c67bacc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/86165f7a3f938c1d46a06a96aeb1764b04257234c2999743bd180490afcf51bdf88c5a22bcff2d67b7340d21d4da731dd35a687c61415adba45c5ca2b83729ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5e42fbb02c5862d8d18e519355b9062b8dfa6bef61b29583a9a228a45bfb09c83b27031902da02d1043fdc4916038b50b84b62666b9299bdc0b12c3b5fbf3630"} 04:28:55 INFO - Running post-run listener: copy_logs_to_upload_dir 04:28:55 INFO - Copying logs to upload dir... 04:28:55 INFO - mkdir: /builds/slave/test/build/upload/logs 04:28:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.285221 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-22 04:28:55.477229) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:28:55.480748) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad8c1096e36cee85478f68c6eb11d245456e87bed19bb444f57747878ee53da9e23cbf26fbef5d1ac512ec72477daa4c6bc5fcea17a5f92419b490f5c67bacc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/86165f7a3f938c1d46a06a96aeb1764b04257234c2999743bd180490afcf51bdf88c5a22bcff2d67b7340d21d4da731dd35a687c61415adba45c5ca2b83729ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5e42fbb02c5862d8d18e519355b9062b8dfa6bef61b29583a9a228a45bfb09c83b27031902da02d1043fdc4916038b50b84b62666b9299bdc0b12c3b5fbf3630"} build_url:https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad8c1096e36cee85478f68c6eb11d245456e87bed19bb444f57747878ee53da9e23cbf26fbef5d1ac512ec72477daa4c6bc5fcea17a5f92419b490f5c67bacc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/86165f7a3f938c1d46a06a96aeb1764b04257234c2999743bd180490afcf51bdf88c5a22bcff2d67b7340d21d4da731dd35a687c61415adba45c5ca2b83729ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5e42fbb02c5862d8d18e519355b9062b8dfa6bef61b29583a9a228a45bfb09c83b27031902da02d1043fdc4916038b50b84b62666b9299bdc0b12c3b5fbf3630"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:28:55.505618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:28:55.505941) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469185387.661079-1516115364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004545 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:28:55.591302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:28:55.591636) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:28:55.591937) ========= ========= Total master_lag: 0.17 =========