builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1469186700.75 results: success (0) buildid: 20160722013039 builduid: 0264508d29cf427f9bb156f4b17ee7ab revision: 21d15da870e8f74b933429f4b3efd651747b8b31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.748279) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.748864) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.749186) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.761224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.761628) ========= 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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 04:25:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-07-22 04:25:01 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145130 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.919082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.919484) ========= 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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018608 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.970403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:00.970831) ========= 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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 04:25:01,534 truncating revision to first 12 chars 2016-07-22 04:25:01,534 Setting DEBUG logging. 2016-07-22 04:25:01,534 attempt 1/10 2016-07-22 04:25:01,534 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:25:01,839 unpacking tar archive at: mozilla-inbound-21d15da870e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619743 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:01.604672) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:01.605027) ========= script_repo_revision: 21d15da870e8f74b933429f4b3efd651747b8b31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:01.605494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:01.605802) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 04:25:01.616800) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-22 04:25:01.617268) ========= /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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:25:02 INFO - MultiFileLogger online at 20160722 04:25:02 in /builds/slave/test 04:25:02 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:25:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:02 INFO - {'append_to_log': False, 04:25:02 INFO - 'base_work_dir': '/builds/slave/test', 04:25:02 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:25:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:02 INFO - 'branch': 'Mozilla-Inbound', 04:25:02 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:02 INFO - 'config_files': ('talos/linux_config.py',), 04:25:02 INFO - 'default_actions': ('clobber', 04:25:02 INFO - 'read-buildbot-config', 04:25:02 INFO - 'download-and-extract', 04:25:02 INFO - 'populate-webroot', 04:25:02 INFO - 'create-virtualenv', 04:25:02 INFO - 'install', 04:25:02 INFO - 'run-tests'), 04:25:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:02 INFO - 'download_minidump_stackwalk': True, 04:25:02 INFO - 'download_symbols': 'ondemand', 04:25:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:02 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:02 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:02 INFO - 'installer_path': 'installer.exe', 04:25:02 INFO - 'log_level': 'info', 04:25:02 INFO - 'log_name': 'talos', 04:25:02 INFO - 'log_to_console': True, 04:25:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:25:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:25:02 INFO - 'opt_config_files': (), 04:25:02 INFO - 'pip_index': False, 04:25:02 INFO - 'sps_profile': False, 04:25:02 INFO - 'sps_profile_interval': 0, 04:25:02 INFO - 'suite': 'dromaeojs', 04:25:02 INFO - 'system_bits': '32', 04:25:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:02 INFO - 'title': 'talos-linux64-ix-039', 04:25:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:02 INFO - 'use_talos_json': True, 04:25:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:02 INFO - 'work_dir': 'build'} 04:25:02 INFO - [mozharness: 2016-07-22 11:25:02.277544Z] Running clobber step. 04:25:02 INFO - Running pre-action listener: _resource_record_pre_action 04:25:02 INFO - Running main action method: clobber 04:25:02 INFO - rmtree: /builds/slave/test/build 04:25:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:02 INFO - Running post-action listener: _resource_record_post_action 04:25:02 INFO - [mozharness: 2016-07-22 11:25:02.756674Z] Finished clobber step (success) 04:25:02 INFO - [mozharness: 2016-07-22 11:25:02.756775Z] Running read-buildbot-config step. 04:25:02 INFO - Running pre-action listener: _resource_record_pre_action 04:25:02 INFO - Running main action method: read_buildbot_config 04:25:02 INFO - Using buildbot properties: 04:25:02 INFO - { 04:25:02 INFO - "project": "", 04:25:02 INFO - "product": "firefox", 04:25:02 INFO - "script_repo_revision": "production", 04:25:02 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:25:02 INFO - "repository": "", 04:25:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:25:02 INFO - "buildid": "20160722013039", 04:25:02 INFO - "pgo_build": "True", 04:25:02 INFO - "basedir": "/builds/slave/test", 04:25:02 INFO - "buildnumber": 1271, 04:25:02 INFO - "slavename": "talos-linux64-ix-039", 04:25:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:25:02 INFO - "platform": "ubuntu64_hw", 04:25:02 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:25:02 INFO - "branch": "mozilla-inbound", 04:25:02 INFO - "repo_path": "integration/mozilla-inbound", 04:25:02 INFO - "revision": "21d15da870e8f74b933429f4b3efd651747b8b31", 04:25:02 INFO - "stage_platform": "linux64-pgo", 04:25:02 INFO - "builduid": "0264508d29cf427f9bb156f4b17ee7ab", 04:25:02 INFO - "slavebuilddir": "test" 04:25:02 INFO - } 04:25:02 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:25:02 INFO - Running post-action listener: _resource_record_post_action 04:25:02 INFO - [mozharness: 2016-07-22 11:25:02.759406Z] Finished read-buildbot-config step (success) 04:25:02 INFO - [mozharness: 2016-07-22 11:25:02.759496Z] Running download-and-extract step. 04:25:02 INFO - Running pre-action listener: _resource_record_pre_action 04:25:02 INFO - Running main action method: download_and_extract 04:25:02 INFO - mkdir: /builds/slave/test/build/tests 04:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:03 INFO - Downloaded 1361 bytes. 04:25:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:25:03 INFO - Using the following test package requirements: 04:25:03 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:25:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:25:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:25:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'jsshell-linux-x86_64.zip'], 04:25:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:25:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:25:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:25:03 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:25:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:25:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:25:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:25:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:25: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:25:03 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:25:03 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:25:03 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:25:03 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:25:03 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:25:04 INFO - Downloaded 24308744 bytes. 04:25:04 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:25:04 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:25:06 INFO - Return code: 0 04:25:06 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:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:25:06 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:25:06 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:25:06 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:25:06 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:25:06 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:25:07 INFO - Downloaded 24308744 bytes. 04:25:07 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:25:07 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:25:08 INFO - Return code: 0 04:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:09 INFO - Downloaded 14403317 bytes. 04:25:09 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:25:09 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:25:09 INFO - Return code: 0 04:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:09 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:25:09 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:25:09 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:25:09 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:25:09 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:25:11 INFO - Downloaded 60855208 bytes. 04:25:11 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:25:11 INFO - mkdir: /builds/slave/test/properties 04:25:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:25:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:25:11 INFO - Contents: 04:25:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NCKEKihkRKqZ5YY2DFqM4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:25:12 INFO - Running post-action listener: _resource_record_post_action 04:25:12 INFO - Running post-action listener: set_extra_try_arguments 04:25:12 INFO - [mozharness: 2016-07-22 11:25:12.098584Z] Finished download-and-extract step (success) 04:25:12 INFO - [mozharness: 2016-07-22 11:25:12.098820Z] Running populate-webroot step. 04:25:12 INFO - Running pre-action listener: _resource_record_pre_action 04:25:12 INFO - Running main action method: populate_webroot 04:25:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:25:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:25:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:25:12 INFO - '--tppagecycles', 04:25:12 INFO - '7'], 04:25:12 INFO - 'tests': ['tsvgm']}}, 04:25:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:25:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:25:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:25:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:25:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['damp', 'tps']}, 04:25:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'tests': ['damp', 'tps']}, 04:25:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['dromaeo_dom']}, 04:25:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:25:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['basic_compositor_video']}, 04:25:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:25:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['a11yr', 04:25:12 INFO - 'ts_paint', 04:25:12 INFO - 'tpaint', 04:25:12 INFO - 'sessionrestore', 04:25:12 INFO - 'sessionrestore_no_auto_restore', 04:25:12 INFO - 'tabpaint']}, 04:25:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['tsvgx', 04:25:12 INFO - 'tsvgr_opacity', 04:25:12 INFO - 'tart', 04:25:12 INFO - 'tscrollx', 04:25:12 INFO - 'cart']}, 04:25:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:25:12 INFO - 'tsvgr_opacity', 04:25:12 INFO - 'tart', 04:25:12 INFO - 'tscrollx', 04:25:12 INFO - 'cart']}, 04:25:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'talos_options': ['--disable-e10s'], 04:25:12 INFO - 'tests': ['tp5o']}, 04:25:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'tests': ['tp5o']}, 04:25:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'talos_options': ['--disable-e10s', 04:25:12 INFO - '--xperf_path', 04:25:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:12 INFO - 'tests': ['tp5n']}, 04:25:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:12 INFO - 'talos_options': ['--xperf_path', 04:25:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:12 INFO - 'tests': ['tp5n']}}, 04:25:12 INFO - 'talos.zip': {'path': '', 04:25:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:25:12 INFO - Running post-action listener: _resource_record_post_action 04:25:12 INFO - [mozharness: 2016-07-22 11:25:12.116315Z] Finished populate-webroot step (success) 04:25:12 INFO - [mozharness: 2016-07-22 11:25:12.116405Z] Running create-virtualenv step. 04:25:12 INFO - Running pre-action listener: _resource_record_pre_action 04:25:12 INFO - Running main action method: create_virtualenv 04:25:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:25:12 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:25:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:25:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:25:12 INFO - Using real prefix '/usr' 04:25:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:25:12 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:14 INFO - Installing pip.................done. 04:25:14 INFO - Return code: 0 04:25:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:14 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:25:14 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:25:14 INFO - Using env: {'DISPLAY': ':0', 04:25:14 INFO - 'HOME': '/home/cltbld', 04:25:14 INFO - 'LANG': 'en_US.UTF-8', 04:25:14 INFO - 'LANGUAGE': 'en_US:en', 04:25:14 INFO - 'LOGNAME': 'cltbld', 04:25:14 INFO - 'MAIL': '/var/mail/cltbld', 04:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:14 INFO - 'MOZ_NO_REMOTE': '1', 04:25:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:14 INFO - 'NO_EM_RESTART': '1', 04:25:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:14 INFO - 'PWD': '/builds/slave/test', 04:25:14 INFO - 'SHELL': '/bin/bash', 04:25:14 INFO - 'SHLVL': '1', 04:25:14 INFO - 'TERM': 'linux', 04:25:14 INFO - 'TMOUT': '86400', 04:25:14 INFO - 'USER': 'cltbld', 04:25:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:14 INFO - '_': '/tools/buildbot/bin/python'} 04:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:14 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:25:14 INFO - Cleaning up... 04:25:14 INFO - Return code: 0 04:25:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:14 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:25:14 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:25:14 INFO - Using env: {'DISPLAY': ':0', 04:25:14 INFO - 'HOME': '/home/cltbld', 04:25:14 INFO - 'LANG': 'en_US.UTF-8', 04:25:14 INFO - 'LANGUAGE': 'en_US:en', 04:25:14 INFO - 'LOGNAME': 'cltbld', 04:25:14 INFO - 'MAIL': '/var/mail/cltbld', 04:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:14 INFO - 'MOZ_NO_REMOTE': '1', 04:25:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:14 INFO - 'NO_EM_RESTART': '1', 04:25:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:14 INFO - 'PWD': '/builds/slave/test', 04:25:14 INFO - 'SHELL': '/bin/bash', 04:25:14 INFO - 'SHLVL': '1', 04:25:14 INFO - 'TERM': 'linux', 04:25:14 INFO - 'TMOUT': '86400', 04:25:14 INFO - 'USER': 'cltbld', 04:25:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:14 INFO - '_': '/tools/buildbot/bin/python'} 04:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:15 INFO - Downloading/unpacking psutil>=3.1.1 04:25:15 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:25:15 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:25:15 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:25:15 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:25:15 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:25:15 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:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:17 INFO - Installing collected packages: psutil 04:25:17 INFO - Running setup.py install for psutil 04:25:17 INFO - building 'psutil._psutil_linux' extension 04:25:17 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:25:17 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:25:17 INFO - building 'psutil._psutil_posix' extension 04:25:17 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:25:17 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:25:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:17 INFO - Successfully installed psutil 04:25:17 INFO - Cleaning up... 04:25:17 INFO - Return code: 0 04:25:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:25:17 INFO - Using env: {'DISPLAY': ':0', 04:25:17 INFO - 'HOME': '/home/cltbld', 04:25:17 INFO - 'LANG': 'en_US.UTF-8', 04:25:17 INFO - 'LANGUAGE': 'en_US:en', 04:25:17 INFO - 'LOGNAME': 'cltbld', 04:25:17 INFO - 'MAIL': '/var/mail/cltbld', 04:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:17 INFO - 'MOZ_NO_REMOTE': '1', 04:25:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:17 INFO - 'NO_EM_RESTART': '1', 04:25:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:17 INFO - 'PWD': '/builds/slave/test', 04:25:17 INFO - 'SHELL': '/bin/bash', 04:25:17 INFO - 'SHLVL': '1', 04:25:17 INFO - 'TERM': 'linux', 04:25:17 INFO - 'TMOUT': '86400', 04:25:17 INFO - 'USER': 'cltbld', 04:25:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:17 INFO - '_': '/tools/buildbot/bin/python'} 04:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:25:19 INFO - Installing collected packages: mozsystemmonitor 04:25:19 INFO - Running setup.py install for mozsystemmonitor 04:25:19 INFO - Successfully installed mozsystemmonitor 04:25:19 INFO - Cleaning up... 04:25:19 INFO - Return code: 0 04:25:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25: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:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:25:19 INFO - Using env: {'DISPLAY': ':0', 04:25:19 INFO - 'HOME': '/home/cltbld', 04:25:19 INFO - 'LANG': 'en_US.UTF-8', 04:25:19 INFO - 'LANGUAGE': 'en_US:en', 04:25:19 INFO - 'LOGNAME': 'cltbld', 04:25:19 INFO - 'MAIL': '/var/mail/cltbld', 04:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:19 INFO - 'MOZ_NO_REMOTE': '1', 04:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:19 INFO - 'NO_EM_RESTART': '1', 04:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:19 INFO - 'PWD': '/builds/slave/test', 04:25:19 INFO - 'SHELL': '/bin/bash', 04:25:19 INFO - 'SHLVL': '1', 04:25:19 INFO - 'TERM': 'linux', 04:25:19 INFO - 'TMOUT': '86400', 04:25:19 INFO - 'USER': 'cltbld', 04:25:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:19 INFO - '_': '/tools/buildbot/bin/python'} 04:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:20 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25:22 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:25:22 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:25:22 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:25:22 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:25:22 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:25:22 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:25:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:23 INFO - warning: no files found matching '*.txt' 04:25:23 INFO - no previously-included directories found matching 'build' 04:25:23 INFO - no previously-included directories found matching 'dist' 04:25:23 INFO - no previously-included directories found matching '.git*' 04:25:23 INFO - Installing collected packages: jsonschema, functools32 04:25:23 INFO - Running setup.py install for functools32 04:25:23 INFO - warning: no files found matching '*.txt' 04:25:23 INFO - no previously-included directories found matching 'build' 04:25:23 INFO - no previously-included directories found matching 'dist' 04:25:23 INFO - no previously-included directories found matching '.git*' 04:25:23 INFO - Successfully installed jsonschema functools32 04:25:23 INFO - Cleaning up... 04:25:23 INFO - Return code: 0 04:25:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:25:23 INFO - Using env: {'DISPLAY': ':0', 04:25:23 INFO - 'HOME': '/home/cltbld', 04:25:23 INFO - 'LANG': 'en_US.UTF-8', 04:25:23 INFO - 'LANGUAGE': 'en_US:en', 04:25:23 INFO - 'LOGNAME': 'cltbld', 04:25:23 INFO - 'MAIL': '/var/mail/cltbld', 04:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:23 INFO - 'MOZ_NO_REMOTE': '1', 04:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:23 INFO - 'NO_EM_RESTART': '1', 04:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:23 INFO - 'PWD': '/builds/slave/test', 04:25:23 INFO - 'SHELL': '/bin/bash', 04:25:23 INFO - 'SHLVL': '1', 04:25:23 INFO - 'TERM': 'linux', 04:25:23 INFO - 'TMOUT': '86400', 04:25:23 INFO - 'USER': 'cltbld', 04:25:23 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:23 INFO - '_': '/tools/buildbot/bin/python'} 04:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:25 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:25:25 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:25:25 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:25:25 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:25:25 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:25:25 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:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:25 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:25:25 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:25:25 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:25:25 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:25:25 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:25:25 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:25:26 INFO - Downloading docopt-0.6.1.tar.gz 04:25:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:26 INFO - Running setup.py install for blobuploader 04:25:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:26 INFO - Running setup.py install for requests 04:25:26 INFO - Running setup.py install for docopt 04:25:26 INFO - Successfully installed blobuploader requests docopt 04:25:26 INFO - Cleaning up... 04:25:26 INFO - Return code: 0 04:25:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:26 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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:26 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:25:26 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:25:26 INFO - Using env: {'DISPLAY': ':0', 04:25:26 INFO - 'HOME': '/home/cltbld', 04:25:26 INFO - 'LANG': 'en_US.UTF-8', 04:25:26 INFO - 'LANGUAGE': 'en_US:en', 04:25:26 INFO - 'LOGNAME': 'cltbld', 04:25:26 INFO - 'MAIL': '/var/mail/cltbld', 04:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:26 INFO - 'MOZ_NO_REMOTE': '1', 04:25:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:26 INFO - 'NO_EM_RESTART': '1', 04:25:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:26 INFO - 'PWD': '/builds/slave/test', 04:25:26 INFO - 'SHELL': '/bin/bash', 04:25:26 INFO - 'SHLVL': '1', 04:25:26 INFO - 'TERM': 'linux', 04:25:26 INFO - 'TMOUT': '86400', 04:25:26 INFO - 'USER': 'cltbld', 04:25:26 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:26 INFO - '_': '/tools/buildbot/bin/python'} 04:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:27 INFO - Running setup.py (path:/tmp/pip-nGSIOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:27 INFO - Running setup.py (path:/tmp/pip-vppyt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:27 INFO - Running setup.py (path:/tmp/pip-WMBm6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:27 INFO - Running setup.py (path:/tmp/pip-IxzS2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:27 INFO - Running setup.py (path:/tmp/pip-sjItZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:27 INFO - Running setup.py (path:/tmp/pip-RE0FQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:27 INFO - Running setup.py (path:/tmp/pip-u5PlWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:27 INFO - Running setup.py (path:/tmp/pip-E7OAR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:27 INFO - Running setup.py (path:/tmp/pip-hUUN08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:27 INFO - Running setup.py (path:/tmp/pip-1TNyT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:27 INFO - Running setup.py (path:/tmp/pip-9csN6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:27 INFO - Running setup.py (path:/tmp/pip-tdLv0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:27 INFO - Running setup.py (path:/tmp/pip-BAp4kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:27 INFO - Running setup.py (path:/tmp/pip-7y8v9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:28 INFO - Running setup.py (path:/tmp/pip-k7HBnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:28 INFO - Running setup.py (path:/tmp/pip-1jdFtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:28 INFO - Running setup.py (path:/tmp/pip-24iE7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:28 INFO - Running setup.py install for manifestparser 04:25:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:28 INFO - Running setup.py install for mozcrash 04:25:28 INFO - Running setup.py install for mozdebug 04:25:28 INFO - Running setup.py install for mozdevice 04:25:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:28 INFO - Running setup.py install for mozfile 04:25:28 INFO - Running setup.py install for mozhttpd 04:25:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:28 INFO - Running setup.py install for mozinfo 04:25:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:28 INFO - Running setup.py install for mozInstall 04:25:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Running setup.py install for mozleak 04:25:29 INFO - Running setup.py install for mozlog 04:25:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Running setup.py install for moznetwork 04:25:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Running setup.py install for mozprocess 04:25:29 INFO - Running setup.py install for mozprofile 04:25:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Running setup.py install for mozrunner 04:25:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:29 INFO - Running setup.py install for mozscreenshot 04:25:29 INFO - Running setup.py install for moztest 04:25:29 INFO - Running setup.py install for mozversion 04:25:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:30 INFO - Cleaning up... 04:25:30 INFO - Return code: 0 04:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:30 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:25:30 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:25:30 INFO - Using env: {'DISPLAY': ':0', 04:25:30 INFO - 'HOME': '/home/cltbld', 04:25:30 INFO - 'LANG': 'en_US.UTF-8', 04:25:30 INFO - 'LANGUAGE': 'en_US:en', 04:25:30 INFO - 'LOGNAME': 'cltbld', 04:25:30 INFO - 'MAIL': '/var/mail/cltbld', 04:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:30 INFO - 'MOZ_NO_REMOTE': '1', 04:25:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:30 INFO - 'NO_EM_RESTART': '1', 04:25:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:30 INFO - 'PWD': '/builds/slave/test', 04:25:30 INFO - 'SHELL': '/bin/bash', 04:25:30 INFO - 'SHLVL': '1', 04:25:30 INFO - 'TERM': 'linux', 04:25:30 INFO - 'TMOUT': '86400', 04:25:30 INFO - 'USER': 'cltbld', 04:25:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:30 INFO - '_': '/tools/buildbot/bin/python'} 04:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:30 INFO - Running setup.py (path:/tmp/pip-wPJfCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:30 INFO - Running setup.py (path:/tmp/pip-_hlgIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:30 INFO - Running setup.py (path:/tmp/pip-gBSgdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:30 INFO - Running setup.py (path:/tmp/pip-Dp_WCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:30 INFO - Running setup.py (path:/tmp/pip-PdrMJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:30 INFO - Running setup.py (path:/tmp/pip-WCS0FB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:30 INFO - Running setup.py (path:/tmp/pip-WP8RZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:30 INFO - Running setup.py (path:/tmp/pip-1QWzIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:30 INFO - Running setup.py (path:/tmp/pip-G0QyVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:30 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:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:30 INFO - Running setup.py (path:/tmp/pip-LVaywA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:31 INFO - Running setup.py (path:/tmp/pip-V49_KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:31 INFO - Running setup.py (path:/tmp/pip-LoDw28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:31 INFO - Running setup.py (path:/tmp/pip-6mgDsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:31 INFO - Running setup.py (path:/tmp/pip-c9Vv4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:31 INFO - Running setup.py (path:/tmp/pip-nPuTqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:31 INFO - Running setup.py (path:/tmp/pip-MpkKEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:31 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:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:31 INFO - Running setup.py (path:/tmp/pip-tGvIth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:31 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:25:31 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:25:31 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:25:31 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:25:31 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:25:31 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:25:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:33 INFO - Downloading blessings-1.6.tar.gz 04:25:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:33 INFO - Installing collected packages: blessings 04:25:33 INFO - Running setup.py install for blessings 04:25:33 INFO - Successfully installed blessings 04:25:33 INFO - Cleaning up... 04:25:33 INFO - Return code: 0 04:25:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:33 INFO - Reading from file tmpfile_stdout 04:25:33 INFO - Current package versions: 04:25:33 INFO - argparse == 1.2.1 04:25:33 INFO - blessings == 1.6 04:25:33 INFO - blobuploader == 1.2.4 04:25:33 INFO - docopt == 0.6.1 04:25:33 INFO - functools32 == 3.2.3-2 04:25:33 INFO - jsonschema == 2.5.1 04:25:33 INFO - manifestparser == 1.1 04:25:33 INFO - mozInstall == 1.12 04:25:33 INFO - mozcrash == 0.17 04:25:33 INFO - mozdebug == 0.1 04:25:33 INFO - mozdevice == 0.48 04:25:33 INFO - mozfile == 1.2 04:25:33 INFO - mozhttpd == 0.7 04:25:33 INFO - mozinfo == 0.9 04:25:33 INFO - mozleak == 0.1 04:25:33 INFO - mozlog == 3.3 04:25:33 INFO - moznetwork == 0.27 04:25:33 INFO - mozprocess == 0.23 04:25:33 INFO - mozprofile == 0.28 04:25:33 INFO - mozrunner == 6.12 04:25:33 INFO - mozscreenshot == 0.1 04:25:33 INFO - mozsystemmonitor == 0.3 04:25:33 INFO - moztest == 0.7 04:25:33 INFO - mozversion == 1.4 04:25:33 INFO - psutil == 3.1.1 04:25:33 INFO - requests == 1.2.3 04:25:33 INFO - wsgiref == 0.1.2 04:25:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:33 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 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25:33 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:25:33 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:25:33 INFO - Using env: {'DISPLAY': ':0', 04:25:33 INFO - 'HOME': '/home/cltbld', 04:25:33 INFO - 'LANG': 'en_US.UTF-8', 04:25:33 INFO - 'LANGUAGE': 'en_US:en', 04:25:33 INFO - 'LOGNAME': 'cltbld', 04:25:33 INFO - 'MAIL': '/var/mail/cltbld', 04:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:33 INFO - 'MOZ_NO_REMOTE': '1', 04:25:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:33 INFO - 'NO_EM_RESTART': '1', 04:25:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:33 INFO - 'PWD': '/builds/slave/test', 04:25:33 INFO - 'SHELL': '/bin/bash', 04:25:33 INFO - 'SHLVL': '1', 04:25:33 INFO - 'TERM': 'linux', 04:25:33 INFO - 'TMOUT': '86400', 04:25:33 INFO - 'USER': 'cltbld', 04:25:33 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:33 INFO - '_': '/tools/buildbot/bin/python'} 04:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 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:25:34 INFO - Cleaning up... 04:25:34 INFO - Return code: 0 04:25:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25: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:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', '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:25: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', 'jsonschema'] in /builds/slave/test/build 04:25: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 jsonschema 04:25:34 INFO - Using env: {'DISPLAY': ':0', 04:25:34 INFO - 'HOME': '/home/cltbld', 04:25:34 INFO - 'LANG': 'en_US.UTF-8', 04:25:34 INFO - 'LANGUAGE': 'en_US:en', 04:25:34 INFO - 'LOGNAME': 'cltbld', 04:25:34 INFO - 'MAIL': '/var/mail/cltbld', 04:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:34 INFO - 'MOZ_NO_REMOTE': '1', 04:25:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:34 INFO - 'NO_EM_RESTART': '1', 04:25:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:34 INFO - 'PWD': '/builds/slave/test', 04:25:34 INFO - 'SHELL': '/bin/bash', 04:25:34 INFO - 'SHLVL': '1', 04:25:34 INFO - 'TERM': 'linux', 04:25:34 INFO - 'TMOUT': '86400', 04:25:34 INFO - 'USER': 'cltbld', 04:25:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:34 INFO - '_': '/tools/buildbot/bin/python'} 04:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:34 INFO - Cleaning up... 04:25:34 INFO - Return code: 0 04:25:34 INFO - Running post-action listener: _resource_record_post_action 04:25:34 INFO - Running post-action listener: _start_resource_monitoring 04:25:34 INFO - Starting resource monitoring. 04:25:34 INFO - [mozharness: 2016-07-22 11:25:34.371769Z] Finished create-virtualenv step (success) 04:25:34 INFO - [mozharness: 2016-07-22 11:25:34.372248Z] Running install step. 04:25:34 INFO - Running pre-action listener: _resource_record_pre_action 04:25:34 INFO - Running main action method: install 04:25:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:34 INFO - Reading from file tmpfile_stdout 04:25:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:34 INFO - Reading from file tmpfile_stdout 04:25:34 INFO - Output received: 04:25:34 INFO - Usage: mozinstall [options] installer 04:25:34 INFO - Options: 04:25:34 INFO - -h, --help show this help message and exit 04:25:34 INFO - -d DEST, --destination=DEST 04:25:34 INFO - Directory to install application into. [default: 04:25:34 INFO - "/builds/slave/test"] 04:25:34 INFO - --app=APP Application being installed. [default: firefox] 04:25:34 INFO - mkdir: /builds/slave/test/build/application 04:25:34 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:25:34 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:25:51 INFO - Reading from file tmpfile_stdout 04:25:51 INFO - Output received: 04:25:51 INFO - /builds/slave/test/build/application/firefox/firefox 04:25:51 INFO - Running post-action listener: _resource_record_post_action 04:25:51 INFO - [mozharness: 2016-07-22 11:25:51.932245Z] Finished install step (success) 04:25:51 INFO - [mozharness: 2016-07-22 11:25:51.932530Z] Running run-tests step. 04:25:51 INFO - Running pre-action listener: _resource_record_pre_action 04:25:51 INFO - Running main action method: run_tests 04:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:51 INFO - Python 2.7.3 04:25:51 INFO - Return code: 0 04:25:51 INFO - grabbing minidump binary from tooltool 04:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:25:51 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 0x2c7b3b0>, '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 0x2c7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:51 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:25:51 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:25:51 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:25:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:52 INFO - Return code: 0 04:25:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:25:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:25:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:52 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-039', '--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:25:52 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-039 --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:25:52 INFO - Using env: {'DISPLAY': ':0', 04:25:52 INFO - 'HOME': '/home/cltbld', 04:25:52 INFO - 'LANG': 'en_US.UTF-8', 04:25:52 INFO - 'LANGUAGE': 'en_US:en', 04:25:52 INFO - 'LOGNAME': 'cltbld', 04:25:52 INFO - 'MAIL': '/var/mail/cltbld', 04:25:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:52 INFO - 'MOZ_NO_REMOTE': '1', 04:25:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:52 INFO - 'NO_EM_RESTART': '1', 04:25:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:52 INFO - 'PWD': '/builds/slave/test', 04:25:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:52 INFO - 'SHELL': '/bin/bash', 04:25:52 INFO - 'SHLVL': '1', 04:25:52 INFO - 'TERM': 'linux', 04:25:52 INFO - 'TMOUT': '86400', 04:25:52 INFO - 'USER': 'cltbld', 04:25:52 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945', 04:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:52 INFO - '_': '/tools/buildbot/bin/python'} 04:25:52 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-039', '--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:25:52 INFO - mozversion application_buildid: 20160722013039 04:25:52 INFO - mozversion application_changeset: 21d15da870e8f74b933429f4b3efd651747b8b31 04:25:52 INFO - mozversion application_display_name: Nightly 04:25:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:52 INFO - mozversion application_name: Firefox 04:25:52 INFO - mozversion application_remotingname: firefox 04:25:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:25:52 INFO - mozversion application_vendor: Mozilla 04:25:52 INFO - mozversion application_version: 50.0a1 04:25:52 INFO - mozversion platform_buildid: 20160722013039 04:25:52 INFO - mozversion platform_changeset: 21d15da870e8f74b933429f4b3efd651747b8b31 04:25:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:25:52 INFO - mozversion platform_version: 50.0a1 04:25:52 INFO - using testdate: 1469186752 04:25:52 INFO - actual date: 1469186752 04:25:52 INFO - starting webserver on 'localhost:37742' 04:25:52 INFO - SUITE-START | Running 2 tests 04:25:52 INFO - TEST-START | dromaeo_css 04:25:52 INFO - operating with platform_type : linux_ 04:25:52 INFO - Initialising browser for dromaeo_css test... 04:25:52 INFO - TEST-INFO | started process 4713 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVbMEc4/profile http://localhost:37742/getInfo.html) 04:25:55 INFO - PROCESS | 4713 | JavaScript error: , line 0: uncaught exception: undefined 04:25:56 INFO - PROCESS | 4713 | __metrics Screen width/height:1600/1200 04:25:56 INFO - PROCESS | 4713 | colorDepth:24 04:25:56 INFO - PROCESS | 4713 | Browser inner width/height: 1024/697 04:25:56 INFO - PROCESS | 4713 | __metrics 04:25:57 INFO - PROCESS | 4713 | 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:25:57 INFO - PROCESS | 4713 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:25:57 INFO - PROCESS | 4713 | console.error: PushService: 04:25:57 INFO - PROCESS | 4713 | stateChangeProcessEnqueue: Error transitioning state 04:25:57 INFO - PROCESS | 4713 | UnknownError 04:25:58 INFO - TEST-INFO | 4713: exit 0 04:25:58 INFO - Browser initialized. 04:25:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:58 INFO - TEST-INFO | started process 4798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVbMEc4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:10 INFO - PROCESS | 4798 | RSS: Main: 158048256 04:26:10 INFO - PROCESS | 4798 | 04:27:01 INFO - PROCESS | 4798 | 1469186821636 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:29:09 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37742/tests/dromaeo/cssquery-ext.html) 04:29:10 INFO - PROCESS | 4798 | RSS: Main: 256184320 04:29:10 INFO - PROCESS | 4798 | 04:32:12 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-ext.html (next: http://localhost:37742/tests/dromaeo/cssquery-jquery.html) 04:32:13 INFO - PROCESS | 4798 | RSS: Main: 248541184 04:32:13 INFO - PROCESS | 4798 | 04:35:15 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37742/tests/dromaeo/cssquery-mootools.html) 04:35:16 INFO - PROCESS | 4798 | RSS: Main: 227647488 04:35:16 INFO - PROCESS | 4798 | 04:38:18 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37742/tests/dromaeo/cssquery-prototype.html) 04:38:18 INFO - PROCESS | 4798 | RSS: Main: 185270272 04:38:18 INFO - PROCESS | 4798 | 04:41:21 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37742/tests/dromaeo/cssquery-yui.html) 04:41:22 INFO - PROCESS | 4798 | RSS: Main: 186339328 04:41:22 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | Cycle 1(1): loaded http://localhost:37742/tests/dromaeo/cssquery-yui.html (next: http://localhost:37742/tests/dromaeo/cssquery-dojo.html) 04:44:24 INFO - PROCESS | 4798 | RSS: Main: 275439616 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | __start_tp_report 04:44:24 INFO - PROCESS | 4798 | _x_x_mozilla_page_load 04:44:24 INFO - PROCESS | 4798 | _x_x_mozilla_page_load_details 04:44:24 INFO - PROCESS | 4798 | |i|pagename|runs| 04:44:24 INFO - PROCESS | 4798 | |0;dojo.html;3062.8621712022955;3063.1729433053074;3074.2160749009;3101.302207003423;3122.9219269518258;5237.685738855671;5277.656952298103;5280.0495910736145;5406.67559946403;5428.4300148484435;5324.148136298193;5355.0628639988045;5374.758135883885;5406.432134680937;5435.076037073697;4777.402824646911;4822.493638167977;4853.466118726938;4860.708357498544;4935.3090567320605;4876.857844675967;4911.803527858881;4918.631102667297;4922.286268491067;4927.457979622239;4753.904921901542;4777.880552986168;4826.951730482685;4871.707697538159;4922.630802689795;4815.374001379798;4819.084373968934;4853.22348424254;4874.317595536628;4922.409310882717;4869.172240719086;4883.926741098886;4891.804327826882;4912.754362281871;4938.901221975576;5224.895502089975;5282.445343238943;5284.656497327662;5293.153095504739;5434.646747961409;5683.829485115453;5692.57305702074;5708.885822283531;5730.36965933747;5730.398308177623;5663.7168141593165;5696.3733989261145;5704.743286552115;5776.10470377088;5776.59563830532;5228.843134705965;5325.174597937326;5368.221607866876;5371.301730775013;5450.291462109901;5889.617174883619;5894.793682221101;5908.7045647718305;5911.586188966731;5918.023526118229;5703.486686198262;5717.914231286534;5718.228039214736;5722.113072473773;5724.914126288109;5613.8596535086135;5655.943440565624;5666.914996275059;5673.8297851064535;5777.393373695785;5717.914231286534;5717.971410142923;5746.9712651436475;5762.567807414461;5779;5694.857628559319;5712.685802280831;5743.052396354555;5763.366029736726;5765.423457654201;4277.080427708058;4307.870763877088;4344.522102568715;4395.120975804852;4432.401625780542;3898.5126859142606;3924.1170736584613;3962.7622342659533;3987.2623564640635;4007.719459637797;5439.5920305976415;5450.04624190765;5456.263404440349;5479.4520547944885;5498.230247765316;5599.552035837123;5806.680632565165;5812.447817457335;5818.8254352369495;5884.47039766423;5329.307190065267;5371.919421208685;5375.650582712111;5375.838724838261;5423.511883930465;4867.440244371943;4928.7289199093675;4938.827141050046;4940.703557786473;4988.700677959334;5734.856628584235;5784.2769653793275;5791.160281759121;5801.9129713054335;5802;5287.10119279731;5663.915041274302;5737.885242295214;5810.622309549865;5815.709214539341;5885.205497257815;5891.3224979127945;5893.528517718487;5904.61619994699;5917.763289468373;5586.916196256978;5649.774009039593;5657.717114144359;5659.575531835047;5663.1788390682295;5669.574781891292;5724;5740.856478588069;5753.884922301614;5808.332041815245;5622.971885140548;5665.150227465913;5669.574781891292;5670.914936276042;5672.4894759471845;5650.434956504317;5664.915026274527;5665.036943719495;5666.886662266649;5669.121286200645;5484.232207490874;5521.337439507285;5545.750441230235;5558.97220513911;5637.408072152365;5609.074502707008;5619.522340601095;5654.067078932095;5664.801731939528;5665.773369065191;5495.53287970511;5508.614396992172;5512.145617429305;5517.393086760533;5518.530924871431 04:44:24 INFO - PROCESS | 4798 | |1;ext.html;604.7429842317014;605.6312733222916;633.3444884544498;645.1935081148567;657.9802605921824;30176.245593860163;44315.33526997098;45324.64026079215;45352.546474535244;45384;46020;46225;46403.535964640345;46416.53583464165;46554.53445465544;9083.95458022709;9240.445573265626;9245.676401325954;9246;9246.722598322061;9067.229285510739;9120;9136.497492637902;9139.908600914005;9143.314251431137;9155.313351498631;9195.908040919605;9214.308926830481;9223.769405764877;9229.723108306762;9146.77133071672;9153.816923661523;9165.404248723811;9173.312001599907;9206.815863682723;9065.184133427958;9135.634574617008;9140.314476414296;9141.360104792668;9146.131117543822;44049.119017619785;44328.335074973904;44522.33216501723;44534.554654453364;44539;3484.8954531364097;3639.636036396366;3647.051766540692;3816.885493435201;4503.54964503547;33775;34062.48906266408;34328.31343373143;34397.6560234397;34715.30569388598;4329.956700433018;4384.583464570861;4428.822847086113;4435;4593.379893714339;386476;387839;388923;389082.99999999715;389295;60272;61133.69433152806;61177;61225.693871530355;61313.693431532556;63549;63603.36396636067;63607.36392636107;63653.36346636567;63811;16830;17075.316987320617;17081.658366832842;17091.829081709024;17121.657566848844;13428.462861485432;13457.192568445924;13470.730585388237;13478.045683375856;13522.797158042637;15582.454614088452;16772.245248963827;16955.576110597332;16989.745153822703;17179.914100429418;6435.485161187094;6608.801735947929;6666.833329166712;6681.198256209286;6689.76585819494;948.767551949763;1036.9585216591402;1040.1418829465722;1092.5738961804902;1114.9554017839357;32368;32981.50527742086;32987.835060824546;33156.86820841466;33429.66570334265;887.3123329419623;1165.4697112813649;1166.3643314393676;1166.743316470392;1176.8587769467547;16722.414715484898;16949.237284322237;17033.32867941855;17197.226124824414;17393.826061739474;525904;529963;530399;530602;531278;60800;60874;60892.39107608956;60906.39093609096;60989;1269.4604792962955;1321.5440672967811;1373.3751143229806;1398.5594537720585;1417.3267697843442;31382.52926206063;34670;34988.47517287294;35058;35614;53770;54698.72650636722;54735.45264547463;54762;54770.452295476534;554223;555078;560672;562273;562811;44398.778006109766;45034;45203.77398112988;48592.271115932585;49143;50238;50295.49704503055;50906.23640645467;51036.23445648244;51088.74455627698;42572.78713606412;42958.57041429546;43703;44138.779306103264;45210;43436.13127737491;43955.78022109997;44001;44108.779456102515;44184.779076104416;41713.16573668449;43045.1390972185;43347;43601.127977440905;43993.560064398946;36364.27271454609;39257.607423925394;39320.80339598284;39478.802605986784;39742.801285994545;28724.569131463464;29297.41405171842;29377.412451751272;29475.410491790473;29561.26096847596 04:44:24 INFO - PROCESS | 4798 | |2;jquery.html;351.15021647612775;364.9872254471094;365.63253929800544;366.6553439766619;379.19799623795666;14650.70698586029;16207;18948.526286842854;19026.904865475688;19151.616967660637;18862.71705924412;19444.805551944475;19772.40682779519;20206.797932020676;20249.39251822444;5595.832125036245;5769.855753606152;5778.682172480512;5782.82651520453;5785.045467497858;5556.749731310463;5578.63738856975;5669.291338582677;5689.374168841424;5692.402297758737;5625.6905870177125;5679.858003549903;5681.602287839853;5684.2326285951485;5688.857778555549;5465.863353416176;5584.357798853143;5586.692731899744;5634.774609015634;5689.662929211632;4775.737334446604;4920.434150072751;4922.926156107661;4923.48303428142;4924.113659541281;11643.883561164364;13900.235487048209;18277.451676449728;18447.815521844743;18589.256429742793;2174.8912554372378;2382.4163080045328;2403.87980600971;2586.6766654168227;2803.0889960762834;9758.316917815755;9801.264905132073;9850.458224797634;10092.798144037079;10252.538635761408;1813.2928158018383;1860.4139695995718;1914.9904250478658;1934.0813113770987;1938.108470103754;82213;83161.58419207926;84072.57963710143;84316.57841710813;87098;9761.609535618496;9882.456464894356;9883.406995580288;9888.824458619092;10031.598736050477;14395.568132956028;14421.92789036048;14449.711005779825;14471.565853024425;14520.346584403727;7984.24149705777;8029.116797152308;8064.395170362246;8072;8220.013598368234;7899.210078992055;7946.205379462008;8120.959395202986;8171.836563268701;8172.101068882418;4383.1452866691325;4431.512533621299;4458.152950939376;4525.773711314421;4549.135664223787;1804.909754512269;1869.0841487670943;1891.2056936086876;1932.6521226179423;1947;1022.6114076650825;1044.639599338227;1049.895010498944;1058.809414305417;1059.1526778577108;20033.298834541012;20241.49396265076;20315.18739250443;20371.796282037285;20427.8978605109;964.6382606522554;1005.9789313846452;1024.6772266735954;1026.8357062869904;1028.0850043461326;10858.61994830193;11221.382823944761;11251.268667536584;11288.604898828502;11312.547498099984;481086;482221;487227;515071;534854;73543;75472;84409.57795210984;94174.52912735392;96538;958.4201558057414;1021.1116328793996;1035.5443604813859;1073.2969904712427;1073.9516721747384;17488.475345739647;18093.09534523295;18595.81404185941;18629.254829806658;18855.528611784815;40927.795361023;41810;43392.78303608588;44154.77922610367;44412.55587444084;62452.687736562846;62556;62619.68690156702;62780.37219627928;64238.67880660567;7755.68977240904;7860.820876868515;8218.178182181735;8225.095239523527;8409.621567029377;8944.37389382737;12842;13245.933770331087;13302.93348533251;13793.793093103812;11283.210175287979;11583.478743456733;12055.397230138633;12227.75544489123;12330.383480826102;11532.94233528827;11728.530858765895;11900.226485278428;11932.463039163433;11990.100742444525;11124.332540047622;11940.701482463008;12088.214266072677;12148.331841748435;12196.146269761386;7757.7672669819995;7872.17342179063;7929.682812687659;8011.198880111942;8448.535330556879;7829.45193836426;9946.900530994597;11966.70083247891;12151.878481215075;12601.558945436866 04:44:24 INFO - PROCESS | 4798 | |3;mootools.html;1473.4032716749714;1525.5499627609856;1650.9504714858558;1784.2862854858063;1790.811964743702;8627.266682332001;9266.351355405124;9274.443533388001;9283.4429934204;9300.906990930089;7827.373810095193;8523.105073967237;8794.86807697885;8803.33974951878;9133.680321188744;2325.825563082767;2353.482233908537;2354.3290162303747;2354.4231663242444;2355.8233132515043;2320;2331.6852224949585;2370.383700237933;2371.67982322386;2375.9168429104984;2290.2213247495847;2311.295055008222;2317.3974766560646;2377.239283429303;2384.1059602649007;2237.876916769577;2267.7505474397885;2270.14869423966;2271.3072512883564;2309.642005489152;2278.7835155660187;2280.4754906371463;2280.988595057014;2282.4179834142356;2283.942901427461;8837.116288371175;9048.230900373475;9069.637214511411;9080.182783549511;9080.591373388213;4966.106100901836;5086.872828179288;5129.281900533928;5131.358580177478;5137.357830271216;3148.401803657298;3210.5344725014975;3355.3792548378383;3364.2934983653217;3364.9495257571157;1082.767205050918;1088.7822435512928;1095.3209010413516;1105.4196546812907;1122.3995162588058;12230.449629766681;12254.571090011896;12257.693557660952;12258.570950016707;12284.631461056182;4308.913821723548;4512.954870451319;4580.610648094949;4581.312803079498;4599.08018396322;10356.792864142826;10392.37645741258;10399.688009359732;10401.479926003669;10411;5161.045206636784;5171.663841850268;5174.146265866165;5175.896482070338;5189.42916279209;4215.78921053946;4321.265384884514;4643.303504474356;4706.435227772689;4760.571548560646;3870.7290489666016;3894.2600905827803;3968.523777146703;3983.820728067244;4015.5181378234797;1178.7996040673106;1179.5399794080315;1191.6961174900346;1195.6413076077142;1202.9759404811855;807.2290962131151;881.3345923827469;902.747230775384;914.3142643017736;968.4721826604517;6045.244344456943;6072.908906366408;6074.392560743979;6100.267967843798;6291.622502649856;1005.215931573374;1015.4973288222377;1049.2445439283704;1061.5859814672283;1093.1746531368924;4149.585041495887;4325.8269669213505;4372.147431250875;4403.3174857897075;4428.269335559661;10566.682999510027;10919.727006824893;10930.289531180448;10940.617078402376;10961.177911656658;10599.099076578577;10868.891311086787;10868.945655271831;10884.292520986111;10895.564177432814;7870.488418252795;8105.027396712314;8120.269175774209;8123.553204573687;8131.7560473185895;5968.492678122235;5985.880282394415;6121.143039974496;6155.815325540241;6283.2774230962195;9791.26565507604;10332.896671033193;10344.431056291674;10350.896491034993;10379.532921018723;10265.12746416533;10448.425336606255;10534.736631583964;10552.525136369035;10574.524146413276;6576.934230657824;7135.143782746104;7638.350740186924;7819.804504887424;7973.601319934096;8270.421070525006;8328.333733301442;8340.04089529712;8351.95824020876;8352.457090289112;7452.403807695263;7492;7500.437467190091;7589.6584653691825;7793.844123117619;7495.138059123053;7514.812129696801;7518;7604.163542010264;7801.726939557088;6876.312368763083;6903.93096069053;6937.306269373023;6954.304569543207;6969.337912898267;5103.5661968733075;5142.845714628567;5301.761420735999;5397.325334333208;5417.864553386197;5445.1832225166545;5561.749721262634;5591.133374326986;5630.774769009194;5642.943570564242 04:44:24 INFO - PROCESS | 4798 | |4;prototype.html;316.9017604542592;319.9600049993751;322.8079292820772;324.1232466178986;328.40722495894903;5260.185236093551;5262.447443018481;5283.339582552181;5284.947150528498;5334.093204155293;5181.196914478252;5183.429822719498;5233.084210263199;5240.266362709214;5243.4232234454175;3886.80565971701;3985.880423587297;4017.734413659688;4066.6136717011836;4085.1625416789634;4068.7558746475156;4078.225137223933;4078.938815917764;4087.7751723655188;4092.7135100542973;4043.4945631796027;4056.5052492716914;4058.086930440642;4058.1072164123843;4088.4889388826396;3877.922441551167;3947.3881548359905;4005.578019802957;4036.2936486114813;4084.305668036411;3576.4635304704243;3578.785272883635;3588.74878758487;3594.245208506217;3601.4957905892934;5073.923891141636;5074.0570469127815;5091.643584949055;5127.128388174019;5173.844784656466;4520.615747661453;4634.490206077328;4639.652026098022;4672.275797251432;4697.53024697531;5373.543248823855;5389.164679474688;5398.406175320712;5401.810936617199;5429.239906413066;3747.7001839852746;3913.119548101683;3948.993006783252;3950.2297052074855;3951.565327813938;5570.164475328693;5582.637128586629;5585.636933599303;5604.271444712243;5607.635503692247;3954.169624378855;3954.6638535724787;3968.968068302262;3972.860949866799;3973.8609148679657;4005.899852503711;4013.234176962127;4020.798960051986;4028.8187031583648;4034.8386064557676;4453.953320969635;4602.608778253819;4608.354830323758;4654.767261636905;4674.392328997209;5360.490753378423;5437.809676661298;5443.401225865152;5445.346558413015;5451.890962180734;1377.435251546861;1380.5030189131905;1388.2711576422257;1401.2573336131866;1405.3535373727996;873.5282947208564;890.225503811675;892.3619611977467;899.3075331994328;904.900460949295;4626.44482662076;4638.634296508737;4675.13510000651;4684.74233917131;4782.736949467812;4985.252212168204;4985.4017517897355;4992.8751781205765;5015.695919060998;5034.162530676277;4381.715188512737;4696.295555666609;4699.765011749399;4741.2651039089;4746.4541577719;2630.592258199982;2634.3019099938147;2650.2049385184755;2650.734926507334;2653;5108.974455127701;5118.436971933084;5126.79492820283;5150.536451719288;5152.990333769832;4745.081774708529;4998.700077995332;5055.34280543527;5061.620378471629;5076.96921231503;5002.599792016557;5003.899142188713;5036.395632524121;5064.037832811754;5066.923996140061;4847.34560834297;4916.532929371704;4975.875603109951;5006.349174607278;5020.447750747488;4987.850364489072;4997.800087996585;5169.302144210634;5576.581756368208;5612.831615051555;5776.913346299725;5785.27684039495;5805.419458054161;5813.7674493021495;5817.331006934257;3864.478295430081;3905.1603905160528;3921.4706014687654;3921.588233235469;3927.626875446828;5374.838754837361;5448.692313844728;5579.637323573955;5626.2685850839125;5670.234518339971;5350.732463376893;5409.269748583891;5487.122060470306;5489.34127904654;5508.779648814163;5435.809746658848;5488.560915126701;5491.890162196813;5491.945080549143;5514.393416724238;5222.399424066281;5394.217838413487;5406.324209473816;5428.212909128075;5463.562914966714;5485.204645326326;5487.734797795305;5490.752916118704;5495.065838807493;5503.422140675331;5369.946300536944;5386.326709161355;5394.379646340721;5396.08266594672;5400.9459905400445 04:44:24 INFO - PROCESS | 4798 | |5;yui.html;764.3770327183344;796.8605494038544;836.1847198980994;853.7609469348585;865.1651156633847;14663.413463461462;15673.529794106194;15731;15733;15749.921250393747;14797.482088126917;15010.399584016626;15023.774643380359;15042.473513427032;15050.849491505081;656.4157899469476;797.8284668796193;803.4536515169682;819.9098099209082;825.2325337436182;819.1317203763999;825.7398919340419;828.1676914700718;828.8673812190051;829.713748756681;879.9560021998907;880.423322723617;881.3213825354505;881.7266647339313;881.9426737262057;694.2987582541618;712.5582139073756;877.9122087791234;880.009988762642;880.7445840706188;522;612.9754809807603;624.5846512069469;624.622103627308;625.7059182184366;14399.280035998263;14434.927825360912;14466.566003019927;14466.927665361605;14473.131612103307;14249.287535623178;14396.280185990763;14624.780628290584;14759.926200369036;14817.259137043106;11672.416379181092;11739;11881.524739010516;11886;11930.821037684442;4419.491758447755;4461.5984561389305;4481.305397663367;4551.453825540956;4712.811487540494;75814.24185758182;76104.61947690281;76348.61825690836;76825.23174768237;77399.22600773977;11509.366984815748;11547.884521154849;11627.53489860413;11715.472803723682;11809.23239989398;21441.356759297247;21521.78478215198;21583.136674533158;21586.676199857015;21609.243676471247;8258.380621453294;8406;8470;8511.787205319793;8797.824043519095;4662.673612847068;4803.687760295569;4818.055279379584;4976.2038073908;4989.750512474362;3568.339857126388;3702.074481379655;3705.481232627434;3705.7405981581032;3707.4068149096015;556.5074908705825;658.1016911915201;666.3403230801472;666.5967089910631;667.569417725574;681.8534015186758;682.6962001909202;694.7116946467261;726.9382102521239;755.9584222867685;9545.427274363561;9549.61801527945;9753.317267791186;9906.07513988806;9912.702618921443;557.827073607183;651.8272657745702;660.6498555765452;682.331315310998;719.7840647805638;4533;4821.536328590632;4985.875353116201;5106.897862042812;5139.152039913447;113355.43322283335;115621;116623;120685.39657301658;120974;94953;98501.50749246209;99371.50314248526;99386;100080.499597503;52728;82615.58692206621;86132.56933715292;87756.5612171935;88701;9565.473898935625;9600.039996000343;9745.463999480096;9766.9023309766;9812.362196457207;50961.7451912738;51511.742441287555;52176.739116304176;52821.47178528319;52880;67146;71938.64030679813;74807;76380.61809691138;77927.61036194782;12376.319302438453;12435.689107772378;12702.682432939251;12743.10798244114;12912.354382281037;15063.189349640994;15481.225938703245;15501.534953951399;15520.5343839685;15585.142817144711;13334;13617.114887532278;13618.319084045956;14494.927525362305;14570.635734106732;14017.929910350384;14070.718585628434;14199;14281.857181428053;14678.779818302948;13313.33433328349;13379.598612041655;13439.126456780277;13462.86537134616;14168;11432.599859004895;11653.53385864556;11679.416029198335;11689.590864319707;11731.88268117308;11579.88420115788;11836.526538938691;11846.170768046499;11967.581134660246;12720.809187862375 04:44:24 INFO - PROCESS | 4798 | __end_tp_report 04:44:24 INFO - PROCESS | 4798 | __start_cc_report 04:44:24 INFO - PROCESS | 4798 | _x_x_mozilla_cycle_collect,1000 04:44:24 INFO - PROCESS | 4798 | __end_cc_report 04:44:24 INFO - PROCESS | 4798 | __startTimestamp1469187864851__endTimestamp 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | ------- Summary: start ------- 04:44:24 INFO - PROCESS | 4798 | Number of tests: 6 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#0] dojo.html Cycles:165 Average:5321.87 Median:5521.34 stddev:604.16 (10.9%) stddev-sans-first:579.44 04:44:24 INFO - PROCESS | 4798 | Values: 3062.9 3063.2 3074.2 3101.3 3122.9 5237.7 5277.7 5280.0 5406.7 5428.4 5324.1 5355.1 5374.8 5406.4 5435.1 4777.4 4822.5 4853.5 4860.7 4935.3 4876.9 4911.8 4918.6 4922.3 4927.5 4753.9 4777.9 4827.0 4871.7 4922.6 4815.4 4819.1 4853.2 4874.3 4922.4 4869.2 4883.9 4891.8 4912.8 4938.9 5224.9 5282.4 5284.7 5293.2 5434.6 5683.8 5692.6 5708.9 5730.4 5730.4 5663.7 5696.4 5704.7 5776.1 5776.6 5228.8 5325.2 5368.2 5371.3 5450.3 5889.6 5894.8 5908.7 5911.6 5918.0 5703.5 5717.9 5718.2 5722.1 5724.9 5613.9 5655.9 5666.9 5673.8 5777.4 5717.9 5718.0 5747.0 5762.6 5779.0 5694.9 5712.7 5743.1 5763.4 5765.4 4277.1 4307.9 4344.5 4395.1 4432.4 3898.5 3924.1 3962.8 3987.3 4007.7 5439.6 5450.0 5456.3 5479.5 5498.2 5599.6 5806.7 5812.4 5818.8 5884.5 5329.3 5371.9 5375.7 5375.8 5423.5 4867.4 4928.7 4938.8 4940.7 4988.7 5734.9 5784.3 5791.2 5801.9 5802.0 5287.1 5663.9 5737.9 5810.6 5815.7 5885.2 5891.3 5893.5 5904.6 5917.8 5586.9 5649.8 5657.7 5659.6 5663.2 5669.6 5724.0 5740.9 5753.9 5808.3 5623.0 5665.2 5669.6 5670.9 5672.5 5650.4 5664.9 5665.0 5666.9 5669.1 5484.2 5521.3 5545.8 5559.0 5637.4 5609.1 5619.5 5654.1 5664.8 5665.8 5495.5 5508.6 5512.1 5517.4 5518.5 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#1] ext.html Cycles:180 Average:66019.43 Median:44528.44 stddev:132138.11 (296.7%) stddev-sans-first:132417.51 04:44:24 INFO - PROCESS | 4798 | Values: 604.7 605.6 633.3 645.2 658.0 30176.2 44315.3 45324.6 45352.5 45384.0 46020.0 46225.0 46403.5 46416.5 46554.5 9084.0 9240.4 9245.7 9246.0 9246.7 9067.2 9120.0 9136.5 9139.9 9143.3 9155.3 9195.9 9214.3 9223.8 9229.7 9146.8 9153.8 9165.4 9173.3 9206.8 9065.2 9135.6 9140.3 9141.4 9146.1 44049.1 44328.3 44522.3 44534.6 44539.0 3484.9 3639.6 3647.1 3816.9 4503.5 33775.0 34062.5 34328.3 34397.7 34715.3 4330.0 4384.6 4428.8 4435.0 4593.4 386476.0 387839.0 388923.0 389083.0 389295.0 60272.0 61133.7 61177.0 61225.7 61313.7 63549.0 63603.4 63607.4 63653.4 63811.0 16830.0 17075.3 17081.7 17091.8 17121.7 13428.5 13457.2 13470.7 13478.0 13522.8 15582.5 16772.2 16955.6 16989.7 17179.9 6435.5 6608.8 6666.8 6681.2 6689.8 948.8 1037.0 1040.1 1092.6 1115.0 32368.0 32981.5 32987.8 33156.9 33429.7 887.3 1165.5 1166.4 1166.7 1176.9 16722.4 16949.2 17033.3 17197.2 17393.8 525904.0 529963.0 530399.0 530602.0 531278.0 60800.0 60874.0 60892.4 60906.4 60989.0 1269.5 1321.5 1373.4 1398.6 1417.3 31382.5 34670.0 34988.5 35058.0 35614.0 53770.0 54698.7 54735.5 54762.0 54770.5 554223.0 555078.0 560672.0 562273.0 562811.0 44398.8 45034.0 45203.8 48592.3 49143.0 50238.0 50295.5 50906.2 51036.2 51088.7 42572.8 42958.6 43703.0 44138.8 45210.0 43436.1 43955.8 44001.0 44108.8 44184.8 41713.2 43045.1 43347.0 43601.1 43993.6 36364.3 39257.6 39320.8 39478.8 39742.8 28724.6 29297.4 29377.4 29475.4 29561.3 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#2] jquery.html Cycles:180 Average:29039.18 Median:366.14 stddev:82517.70 (22537.0%) stddev-sans-first:82721.07 04:44:24 INFO - PROCESS | 4798 | Values: 351.2 365.0 365.6 366.7 379.2 14650.7 16207.0 18948.5 19026.9 19151.6 18862.7 19444.8 19772.4 20206.8 20249.4 5595.8 5769.9 5778.7 5782.8 5785.0 5556.7 5578.6 5669.3 5689.4 5692.4 5625.7 5679.9 5681.6 5684.2 5688.9 5465.9 5584.4 5586.7 5634.8 5689.7 4775.7 4920.4 4922.9 4923.5 4924.1 11643.9 13900.2 18277.5 18447.8 18589.3 2174.9 2382.4 2403.9 2586.7 2803.1 9758.3 9801.3 9850.5 10092.8 10252.5 1813.3 1860.4 1915.0 1934.1 1938.1 82213.0 83161.6 84072.6 84316.6 87098.0 9761.6 9882.5 9883.4 9888.8 10031.6 14395.6 14421.9 14449.7 14471.6 14520.3 7984.2 8029.1 8064.4 8072.0 8220.0 7899.2 7946.2 8121.0 8171.8 8172.1 4383.1 4431.5 4458.2 4525.8 4549.1 1804.9 1869.1 1891.2 1932.7 1947.0 1022.6 1044.6 1049.9 1058.8 1059.2 20033.3 20241.5 20315.2 20371.8 20427.9 964.6 1006.0 1024.7 1026.8 1028.1 10858.6 11221.4 11251.3 11288.6 11312.5 481086.0 482221.0 487227.0 515071.0 534854.0 73543.0 75472.0 84409.6 94174.5 96538.0 958.4 1021.1 1035.5 1073.3 1074.0 17488.5 18093.1 18595.8 18629.3 18855.5 40927.8 41810.0 43392.8 44154.8 44412.6 62452.7 62556.0 62619.7 62780.4 64238.7 7755.7 7860.8 8218.2 8225.1 8409.6 8944.4 12842.0 13245.9 13302.9 13793.8 11283.2 11583.5 12055.4 12227.8 12330.4 11532.9 11728.5 11900.2 11932.5 11990.1 11124.3 11940.7 12088.2 12148.3 12196.1 7757.8 7872.2 7929.7 8011.2 8448.5 7829.5 9946.9 11966.7 12151.9 12601.6 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#3] mootools.html Cycles:180 Average:5810.09 Median:4415.79 stddev:3308.39 (74.9%) stddev-sans-first:3301.62 04:44:24 INFO - PROCESS | 4798 | Values: 1473.4 1525.5 1651.0 1784.3 1790.8 8627.3 9266.4 9274.4 9283.4 9300.9 7827.4 8523.1 8794.9 8803.3 9133.7 2325.8 2353.5 2354.3 2354.4 2355.8 2320.0 2331.7 2370.4 2371.7 2375.9 2290.2 2311.3 2317.4 2377.2 2384.1 2237.9 2267.8 2270.1 2271.3 2309.6 2278.8 2280.5 2281.0 2282.4 2283.9 8837.1 9048.2 9069.6 9080.2 9080.6 4966.1 5086.9 5129.3 5131.4 5137.4 3148.4 3210.5 3355.4 3364.3 3364.9 1082.8 1088.8 1095.3 1105.4 1122.4 12230.4 12254.6 12257.7 12258.6 12284.6 4308.9 4513.0 4580.6 4581.3 4599.1 10356.8 10392.4 10399.7 10401.5 10411.0 5161.0 5171.7 5174.1 5175.9 5189.4 4215.8 4321.3 4643.3 4706.4 4760.6 3870.7 3894.3 3968.5 3983.8 4015.5 1178.8 1179.5 1191.7 1195.6 1203.0 807.2 881.3 902.7 914.3 968.5 6045.2 6072.9 6074.4 6100.3 6291.6 1005.2 1015.5 1049.2 1061.6 1093.2 4149.6 4325.8 4372.1 4403.3 4428.3 10566.7 10919.7 10930.3 10940.6 10961.2 10599.1 10868.9 10868.9 10884.3 10895.6 7870.5 8105.0 8120.3 8123.6 8131.8 5968.5 5985.9 6121.1 6155.8 6283.3 9791.3 10332.9 10344.4 10350.9 10379.5 10265.1 10448.4 10534.7 10552.5 10574.5 6576.9 7135.1 7638.4 7819.8 7973.6 8270.4 8328.3 8340.0 8352.0 8352.5 7452.4 7492.0 7500.4 7589.7 7793.8 7495.1 7514.8 7518.0 7604.2 7801.7 6876.3 6903.9 6937.3 6954.3 6969.3 5103.6 5142.8 5301.8 5397.3 5417.9 5445.2 5561.7 5591.1 5630.8 5642.9 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#4] prototype.html Cycles:180 Average:4452.30 Median:5003.25 stddev:1296.84 (25.9%) stddev-sans-first:1262.78 04:44:24 INFO - PROCESS | 4798 | Values: 316.9 320.0 322.8 324.1 328.4 5260.2 5262.4 5283.3 5284.9 5334.1 5181.2 5183.4 5233.1 5240.3 5243.4 3886.8 3985.9 4017.7 4066.6 4085.2 4068.8 4078.2 4078.9 4087.8 4092.7 4043.5 4056.5 4058.1 4058.1 4088.5 3877.9 3947.4 4005.6 4036.3 4084.3 3576.5 3578.8 3588.7 3594.2 3601.5 5073.9 5074.1 5091.6 5127.1 5173.8 4520.6 4634.5 4639.7 4672.3 4697.5 5373.5 5389.2 5398.4 5401.8 5429.2 3747.7 3913.1 3949.0 3950.2 3951.6 5570.2 5582.6 5585.6 5604.3 5607.6 3954.2 3954.7 3969.0 3972.9 3973.9 4005.9 4013.2 4020.8 4028.8 4034.8 4454.0 4602.6 4608.4 4654.8 4674.4 5360.5 5437.8 5443.4 5445.3 5451.9 1377.4 1380.5 1388.3 1401.3 1405.4 873.5 890.2 892.4 899.3 904.9 4626.4 4638.6 4675.1 4684.7 4782.7 4985.3 4985.4 4992.9 5015.7 5034.2 4381.7 4696.3 4699.8 4741.3 4746.5 2630.6 2634.3 2650.2 2650.7 2653.0 5109.0 5118.4 5126.8 5150.5 5153.0 4745.1 4998.7 5055.3 5061.6 5077.0 5002.6 5003.9 5036.4 5064.0 5066.9 4847.3 4916.5 4975.9 5006.3 5020.4 4987.9 4997.8 5169.3 5576.6 5612.8 5776.9 5785.3 5805.4 5813.8 5817.3 3864.5 3905.2 3921.5 3921.6 3927.6 5374.8 5448.7 5579.6 5626.3 5670.2 5350.7 5409.3 5487.1 5489.3 5508.8 5435.8 5488.6 5491.9 5491.9 5514.4 5222.4 5394.2 5406.3 5428.2 5463.6 5485.2 5487.7 5490.8 5495.1 5503.4 5369.9 5386.3 5394.4 5396.1 5400.9 04:44:24 INFO - PROCESS | 4798 | 04:44:24 INFO - PROCESS | 4798 | [#5] yui.html Cycles:180 Average:20785.31 Median:4898.87 stddev:29757.07 (607.4%) stddev-sans-first:29802.57 04:44:24 INFO - PROCESS | 4798 | Values: 764.4 796.9 836.2 853.8 865.2 14663.4 15673.5 15731.0 15733.0 15749.9 14797.5 15010.4 15023.8 15042.5 15050.8 656.4 797.8 803.5 819.9 825.2 819.1 825.7 828.2 828.9 829.7 880.0 880.4 881.3 881.7 881.9 694.3 712.6 877.9 880.0 880.7 522.0 613.0 624.6 624.6 625.7 14399.3 14434.9 14466.6 14466.9 14473.1 14249.3 14396.3 14624.8 14759.9 14817.3 11672.4 11739.0 11881.5 11886.0 11930.8 4419.5 4461.6 4481.3 4551.5 4712.8 75814.2 76104.6 76348.6 76825.2 77399.2 11509.4 11547.9 11627.5 11715.5 11809.2 21441.4 21521.8 21583.1 21586.7 21609.2 8258.4 8406.0 8470.0 8511.8 8797.8 4662.7 4803.7 4818.1 4976.2 4989.8 3568.3 3702.1 3705.5 3705.7 3707.4 556.5 658.1 666.3 666.6 667.6 681.9 682.7 694.7 726.9 756.0 9545.4 9549.6 9753.3 9906.1 9912.7 557.8 651.8 660.6 682.3 719.8 4533.0 4821.5 4985.9 5106.9 5139.2 113355.4 115621.0 116623.0 120685.4 120974.0 94953.0 98501.5 99371.5 99386.0 100080.5 52728.0 82615.6 86132.6 87756.6 88701.0 9565.5 9600.0 9745.5 9766.9 9812.4 50961.7 51511.7 52176.7 52821.5 52880.0 67146.0 71938.6 74807.0 76380.6 77927.6 12376.3 12435.7 12702.7 12743.1 12912.4 15063.2 15481.2 15501.5 15520.5 15585.1 13334.0 13617.1 13618.3 14494.9 14570.6 14017.9 14070.7 14199.0 14281.9 14678.8 13313.3 13379.6 13439.1 13462.9 14168.0 11432.6 11653.5 11679.4 11689.6 11731.9 11579.9 11836.5 11846.2 11967.6 12720.8 04:44:24 INFO - PROCESS | 4798 | -------- Summary: end -------- 04:44:24 INFO - PROCESS | 4798 | 04:44:25 INFO - TEST-INFO | 4798: exit 0 04:44:25 INFO - TEST-OK | dromaeo_css | took 1113445ms 04:44:25 INFO - TEST-START | kraken 04:44:25 INFO - operating with platform_type : linux_ 04:44:25 INFO - Initialising browser for kraken test... 04:44:25 INFO - TEST-INFO | started process 4986 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMVF5pP/profile http://localhost:37742/getInfo.html) 04:44:28 INFO - PROCESS | 4986 | JavaScript error: , line 0: uncaught exception: undefined 04:44:29 INFO - PROCESS | 4986 | __metrics Screen width/height:1600/1200 04:44:29 INFO - PROCESS | 4986 | colorDepth:24 04:44:29 INFO - PROCESS | 4986 | Browser inner width/height: 1024/697 04:44:29 INFO - PROCESS | 4986 | __metrics 04:44:30 INFO - PROCESS | 4986 | 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:44:30 INFO - PROCESS | 4986 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:44:31 INFO - TEST-INFO | 4986: exit 0 04:44:31 INFO - Browser initialized. 04:44:31 INFO - Running cycle 1/1 for kraken test... 04:44:31 INFO - TEST-INFO | started process 5066 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMVF5pP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:43 INFO - PROCESS | 5066 | RSS: Main: 158064640 04:44:43 INFO - PROCESS | 5066 | 04:44:45 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37742/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:46 INFO - PROCESS | 5066 | RSS: Main: 214323200 04:44:46 INFO - PROCESS | 5066 | 04:44:49 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37742/tests/kraken/driver.html?testName=audio-dft) 04:44:50 INFO - PROCESS | 5066 | RSS: Main: 188252160 04:44:50 INFO - PROCESS | 5066 | 04:44:53 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37742/tests/kraken/driver.html?testName=audio-fft) 04:44:53 INFO - PROCESS | 5066 | RSS: Main: 270094336 04:44:53 INFO - PROCESS | 5066 | 04:44:55 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37742/tests/kraken/driver.html?testName=audio-oscillator) 04:44:56 INFO - PROCESS | 5066 | RSS: Main: 180998144 04:44:56 INFO - PROCESS | 5066 | 04:44:58 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37742/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:59 INFO - PROCESS | 5066 | RSS: Main: 185585664 04:44:59 INFO - PROCESS | 5066 | 04:45:02 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37742/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:02 INFO - PROCESS | 5066 | RSS: Main: 220753920 04:45:02 INFO - PROCESS | 5066 | 04:45:06 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37742/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:06 INFO - PROCESS | 5066 | RSS: Main: 221204480 04:45:06 INFO - PROCESS | 5066 | 04:45:09 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37742/tests/kraken/driver.html?testName=json-parse-financial) 04:45:10 INFO - PROCESS | 5066 | RSS: Main: 220872704 04:45:10 INFO - PROCESS | 5066 | 04:45:11 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37742/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:12 INFO - PROCESS | 5066 | RSS: Main: 185430016 04:45:12 INFO - PROCESS | 5066 | 04:45:14 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:14 INFO - PROCESS | 5066 | RSS: Main: 191275008 04:45:14 INFO - PROCESS | 5066 | 04:45:16 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:45:17 INFO - PROCESS | 5066 | RSS: Main: 195653632 04:45:17 INFO - PROCESS | 5066 | 04:45:20 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:45:20 INFO - PROCESS | 5066 | RSS: Main: 189063168 04:45:20 INFO - PROCESS | 5066 | 04:45:23 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:45:23 INFO - PROCESS | 5066 | RSS: Main: 194727936 04:45:23 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | Cycle 1(1): loaded http://localhost:37742/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37742/tests/kraken/driver.html?testName=ai-astar) 04:45:25 INFO - PROCESS | 5066 | RSS: Main: 245059584 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | __start_tp_report 04:45:25 INFO - PROCESS | 5066 | _x_x_mozilla_page_load 04:45:25 INFO - PROCESS | 5066 | _x_x_mozilla_page_load_details 04:45:25 INFO - PROCESS | 5066 | |i|pagename|runs| 04:45:25 INFO - PROCESS | 5066 | |0;ai-astar;103;112;111;110;110;111;111;111;109;110 04:45:25 INFO - PROCESS | 5066 | |1;audio-beat-detection;148;116;128;177;123;123;146;121;155;118 04:45:25 INFO - PROCESS | 5066 | |2;audio-dft;161;160;162;161;159;160;160;162;165;163 04:45:25 INFO - PROCESS | 5066 | |3;audio-fft;75;74;75;113;77;76;74;74;102;74 04:45:25 INFO - PROCESS | 5066 | |4;audio-oscillator;84;84;126;85;85;85;122;84;84;132 04:45:25 INFO - PROCESS | 5066 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;116;117;117 04:45:25 INFO - PROCESS | 5066 | |6;imaging-darkroom;146;146;146;146;146;147;145;146;145;146 04:45:25 INFO - PROCESS | 5066 | |7;imaging-desaturate;95;95;95;95;100;95;95;95;95;95 04:45:25 INFO - PROCESS | 5066 | |8;json-parse-financial;52;51;51;52;51;51;51;52;51;51 04:45:25 INFO - PROCESS | 5066 | |9;json-stringify-tinderbox;60;61;61;61;62;60;61;62;61;61 04:45:25 INFO - PROCESS | 5066 | |10;stanford-crypto-aes;78;75;75;76;75;75;74;81;80;99 04:45:25 INFO - PROCESS | 5066 | |11;stanford-crypto-ccm;120;120;127;118;120;126;126;118;194;134 04:45:25 INFO - PROCESS | 5066 | |12;stanford-crypto-pbkdf2;161;155;159;156;160;162;154;158;159;156 04:45:25 INFO - PROCESS | 5066 | |13;stanford-crypto-sha256-iterative;68;64;62;62;62;63;62;62;62;66 04:45:25 INFO - PROCESS | 5066 | __end_tp_report 04:45:25 INFO - PROCESS | 5066 | __start_cc_report 04:45:25 INFO - PROCESS | 5066 | _x_x_mozilla_cycle_collect,1683 04:45:25 INFO - PROCESS | 5066 | __end_cc_report 04:45:25 INFO - PROCESS | 5066 | __startTimestamp1469187925848__endTimestamp 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | ------- Summary: start ------- 04:45:25 INFO - PROCESS | 5066 | Number of tests: 14 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:2.53 (2.3%) stddev-sans-first:0.88 04:45:25 INFO - PROCESS | 5066 | Values: 103.0 112.0 111.0 110.0 110.0 111.0 111.0 111.0 109.0 110.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:137.00 stddev:20.10 (14.7%) stddev-sans-first:20.80 04:45:25 INFO - PROCESS | 5066 | Values: 148.0 116.0 128.0 177.0 123.0 123.0 146.0 121.0 155.0 118.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.77 (1.1%) stddev-sans-first:1.87 04:45:25 INFO - PROCESS | 5066 | Values: 161.0 160.0 162.0 161.0 159.0 160.0 160.0 162.0 165.0 163.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#3] audio-fft Cycles:10 Average:81.40 Median:74.50 stddev:14.03 (18.8%) stddev-sans-first:14.69 04:45:25 INFO - PROCESS | 5066 | Values: 75.0 74.0 75.0 113.0 77.0 76.0 74.0 74.0 102.0 74.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.00 stddev:20.55 (24.5%) stddev-sans-first:21.24 04:45:25 INFO - PROCESS | 5066 | Values: 84.0 84.0 126.0 85.0 85.0 85.0 122.0 84.0 84.0 132.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:45:25 INFO - PROCESS | 5066 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:45:25 INFO - PROCESS | 5066 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 145.0 146.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.58 (1.7%) stddev-sans-first:1.67 04:45:25 INFO - PROCESS | 5066 | Values: 95.0 95.0 95.0 95.0 100.0 95.0 95.0 95.0 95.0 95.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 04:45:25 INFO - PROCESS | 5066 | Values: 52.0 51.0 51.0 52.0 51.0 51.0 51.0 52.0 51.0 51.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 04:45:25 INFO - PROCESS | 5066 | Values: 60.0 61.0 61.0 61.0 62.0 60.0 61.0 62.0 61.0 61.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:77.00 stddev:7.48 (9.7%) stddev-sans-first:7.93 04:45:25 INFO - PROCESS | 5066 | Values: 78.0 75.0 75.0 76.0 75.0 75.0 74.0 81.0 80.0 99.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:126.00 stddev:22.95 (18.2%) stddev-sans-first:24.04 04:45:25 INFO - PROCESS | 5066 | Values: 120.0 120.0 127.0 118.0 120.0 126.0 126.0 118.0 194.0 134.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:159.00 stddev:2.67 (1.7%) stddev-sans-first:2.60 04:45:25 INFO - PROCESS | 5066 | Values: 161.0 155.0 159.0 156.0 160.0 162.0 154.0 158.0 159.0 156.0 04:45:25 INFO - PROCESS | 5066 | 04:45:25 INFO - PROCESS | 5066 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:62.50 stddev:2.11 (3.4%) stddev-sans-first:1.39 04:45:25 INFO - PROCESS | 5066 | Values: 68.0 64.0 62.0 62.0 62.0 63.0 62.0 62.0 62.0 66.0 04:45:25 INFO - PROCESS | 5066 | -------- Summary: end -------- 04:45:25 INFO - PROCESS | 5066 | 04:45:26 INFO - TEST-INFO | 5066: exit 0 04:45:26 INFO - TEST-OK | kraken | took 61236ms 04:45:26 INFO - SUITE-END | took 1174s 04:45:27 INFO - Completed test suite (00:19:34) 04:45:27 INFO - javascript benchmark 04:45:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3062.8621712022955, 3063.1729433053074, 3074.2160749009, 3101.302207003423, 3122.9219269518258, 5237.685738855671, 5277.656952298103, 5280.0495910736145, 5406.67559946403, 5428.4300148484435, 5324.148136298193, 5355.0628639988045, 5374.758135883885, 5406.432134680937, 5435.076037073697, 4777.402824646911, 4822.493638167977, 4853.466118726938, 4860.708357498544, 4935.3090567320605, 4876.857844675967, 4911.803527858881, 4918.631102667297, 4922.286268491067, 4927.457979622239, 4753.904921901542, 4777.880552986168, 4826.951730482685, 4871.707697538159, 4922.630802689795, 4815.374001379798, 4819.084373968934, 4853.22348424254, 4874.317595536628, 4922.409310882717, 4869.172240719086, 4883.926741098886, 4891.804327826882, 4912.754362281871, 4938.901221975576, 5224.895502089975, 5282.445343238943, 5284.656497327662, 5293.153095504739, 5434.646747961409, 5683.829485115453, 5692.57305702074, 5708.885822283531, 5730.36965933747, 5730.398308177623, 5663.7168141593165, 5696.3733989261145, 5704.743286552115, 5776.10470377088, 5776.59563830532, 5228.843134705965, 5325.174597937326, 5368.221607866876, 5371.301730775013, 5450.291462109901, 5889.617174883619, 5894.793682221101, 5908.7045647718305, 5911.586188966731, 5918.023526118229, 5703.486686198262, 5717.914231286534, 5718.228039214736, 5722.113072473773, 5724.914126288109, 5613.8596535086135, 5655.943440565624, 5666.914996275059, 5673.8297851064535, 5777.393373695785, 5717.914231286534, 5717.971410142923, 5746.9712651436475, 5762.567807414461, 5779.0, 5694.857628559319, 5712.685802280831, 5743.052396354555, 5763.366029736726, 5765.423457654201, 4277.080427708058, 4307.870763877088, 4344.522102568715, 4395.120975804852, 4432.401625780542, 3898.5126859142606, 3924.1170736584613, 3962.7622342659533, 3987.2623564640635, 4007.719459637797, 5439.5920305976415, 5450.04624190765, 5456.263404440349, 5479.4520547944885, 5498.230247765316, 5599.552035837123, 5806.680632565165, 5812.447817457335, 5818.8254352369495, 5884.47039766423, 5329.307190065267, 5371.919421208685, 5375.650582712111, 5375.838724838261, 5423.511883930465, 4867.440244371943, 4928.7289199093675, 4938.827141050046, 4940.703557786473, 4988.700677959334, 5734.856628584235, 5784.2769653793275, 5791.160281759121, 5801.9129713054335, 5802.0, 5287.10119279731, 5663.915041274302, 5737.885242295214, 5810.622309549865, 5815.709214539341, 5885.205497257815, 5891.3224979127945, 5893.528517718487, 5904.61619994699, 5917.763289468373, 5586.916196256978, 5649.774009039593, 5657.717114144359, 5659.575531835047, 5663.1788390682295, 5669.574781891292, 5724.0, 5740.856478588069, 5753.884922301614, 5808.332041815245, 5622.971885140548, 5665.150227465913, 5669.574781891292, 5670.914936276042, 5672.4894759471845, 5650.434956504317, 5664.915026274527, 5665.036943719495, 5666.886662266649, 5669.121286200645, 5484.232207490874, 5521.337439507285, 5545.750441230235, 5558.97220513911, 5637.408072152365, 5609.074502707008, 5619.522340601095, 5654.067078932095, 5664.801731939528, 5665.773369065191, 5495.53287970511, 5508.614396992172, 5512.145617429305, 5517.393086760533, 5518.530924871431], "value": 5281.030309081257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7429842317014, 605.6312733222916, 633.3444884544498, 645.1935081148567, 657.9802605921824, 30176.245593860163, 44315.33526997098, 45324.64026079215, 45352.546474535244, 45384.0, 46020.0, 46225.0, 46403.535964640345, 46416.53583464165, 46554.53445465544, 9083.95458022709, 9240.445573265626, 9245.676401325954, 9246.0, 9246.722598322061, 9067.229285510739, 9120.0, 9136.497492637902, 9139.908600914005, 9143.314251431137, 9155.313351498631, 9195.908040919605, 9214.308926830481, 9223.769405764877, 9229.723108306762, 9146.77133071672, 9153.816923661523, 9165.404248723811, 9173.312001599907, 9206.815863682723, 9065.184133427958, 9135.634574617008, 9140.314476414296, 9141.360104792668, 9146.131117543822, 44049.119017619785, 44328.335074973904, 44522.33216501723, 44534.554654453364, 44539.0, 3484.8954531364097, 3639.636036396366, 3647.051766540692, 3816.885493435201, 4503.54964503547, 33775.0, 34062.48906266408, 34328.31343373143, 34397.6560234397, 34715.30569388598, 4329.956700433018, 4384.583464570861, 4428.822847086113, 4435.0, 4593.379893714339, 386476.0, 387839.0, 388923.0, 389082.99999999715, 389295.0, 60272.0, 61133.69433152806, 61177.0, 61225.693871530355, 61313.693431532556, 63549.0, 63603.36396636067, 63607.36392636107, 63653.36346636567, 63811.0, 16830.0, 17075.316987320617, 17081.658366832842, 17091.829081709024, 17121.657566848844, 13428.462861485432, 13457.192568445924, 13470.730585388237, 13478.045683375856, 13522.797158042637, 15582.454614088452, 16772.245248963827, 16955.576110597332, 16989.745153822703, 17179.914100429418, 6435.485161187094, 6608.801735947929, 6666.833329166712, 6681.198256209286, 6689.76585819494, 948.767551949763, 1036.9585216591402, 1040.1418829465722, 1092.5738961804902, 1114.9554017839357, 32368.0, 32981.50527742086, 32987.835060824546, 33156.86820841466, 33429.66570334265, 887.3123329419623, 1165.4697112813649, 1166.3643314393676, 1166.743316470392, 1176.8587769467547, 16722.414715484898, 16949.237284322237, 17033.32867941855, 17197.226124824414, 17393.826061739474, 525904.0, 529963.0, 530399.0, 530602.0, 531278.0, 60800.0, 60874.0, 60892.39107608956, 60906.39093609096, 60989.0, 1269.4604792962955, 1321.5440672967811, 1373.3751143229806, 1398.5594537720585, 1417.3267697843442, 31382.52926206063, 34670.0, 34988.47517287294, 35058.0, 35614.0, 53770.0, 54698.72650636722, 54735.45264547463, 54762.0, 54770.452295476534, 554223.0, 555078.0, 560672.0, 562273.0, 562811.0, 44398.778006109766, 45034.0, 45203.77398112988, 48592.271115932585, 49143.0, 50238.0, 50295.49704503055, 50906.23640645467, 51036.23445648244, 51088.74455627698, 42572.78713606412, 42958.57041429546, 43703.0, 44138.779306103264, 45210.0, 43436.13127737491, 43955.78022109997, 44001.0, 44108.779456102515, 44184.779076104416, 41713.16573668449, 43045.1390972185, 43347.0, 43601.127977440905, 43993.560064398946, 36364.27271454609, 39257.607423925394, 39320.80339598284, 39478.802605986784, 39742.801285994545, 28724.569131463464, 29297.41405171842, 29377.412451751272, 29475.410491790473, 29561.26096847596], "value": 21160.807545743017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.15021647612775, 364.9872254471094, 365.63253929800544, 366.6553439766619, 379.19799623795666, 14650.70698586029, 16207.0, 18948.526286842854, 19026.904865475688, 19151.616967660637, 18862.71705924412, 19444.805551944475, 19772.40682779519, 20206.797932020676, 20249.39251822444, 5595.832125036245, 5769.855753606152, 5778.682172480512, 5782.82651520453, 5785.045467497858, 5556.749731310463, 5578.63738856975, 5669.291338582677, 5689.374168841424, 5692.402297758737, 5625.6905870177125, 5679.858003549903, 5681.602287839853, 5684.2326285951485, 5688.857778555549, 5465.863353416176, 5584.357798853143, 5586.692731899744, 5634.774609015634, 5689.662929211632, 4775.737334446604, 4920.434150072751, 4922.926156107661, 4923.48303428142, 4924.113659541281, 11643.883561164364, 13900.235487048209, 18277.451676449728, 18447.815521844743, 18589.256429742793, 2174.8912554372378, 2382.4163080045328, 2403.87980600971, 2586.6766654168227, 2803.0889960762834, 9758.316917815755, 9801.264905132073, 9850.458224797634, 10092.798144037079, 10252.538635761408, 1813.2928158018383, 1860.4139695995718, 1914.9904250478658, 1934.0813113770987, 1938.108470103754, 82213.0, 83161.58419207926, 84072.57963710143, 84316.57841710813, 87098.0, 9761.609535618496, 9882.456464894356, 9883.406995580288, 9888.824458619092, 10031.598736050477, 14395.568132956028, 14421.92789036048, 14449.711005779825, 14471.565853024425, 14520.346584403727, 7984.24149705777, 8029.116797152308, 8064.395170362246, 8072.0, 8220.013598368234, 7899.210078992055, 7946.205379462008, 8120.959395202986, 8171.836563268701, 8172.101068882418, 4383.1452866691325, 4431.512533621299, 4458.152950939376, 4525.773711314421, 4549.135664223787, 1804.909754512269, 1869.0841487670943, 1891.2056936086876, 1932.6521226179423, 1947.0, 1022.6114076650825, 1044.639599338227, 1049.895010498944, 1058.809414305417, 1059.1526778577108, 20033.298834541012, 20241.49396265076, 20315.18739250443, 20371.796282037285, 20427.8978605109, 964.6382606522554, 1005.9789313846452, 1024.6772266735954, 1026.8357062869904, 1028.0850043461326, 10858.61994830193, 11221.382823944761, 11251.268667536584, 11288.604898828502, 11312.547498099984, 481086.0, 482221.0, 487227.0, 515071.0, 534854.0, 73543.0, 75472.0, 84409.57795210984, 94174.52912735392, 96538.0, 958.4201558057414, 1021.1116328793996, 1035.5443604813859, 1073.2969904712427, 1073.9516721747384, 17488.475345739647, 18093.09534523295, 18595.81404185941, 18629.254829806658, 18855.528611784815, 40927.795361023, 41810.0, 43392.78303608588, 44154.77922610367, 44412.55587444084, 62452.687736562846, 62556.0, 62619.68690156702, 62780.37219627928, 64238.67880660567, 7755.68977240904, 7860.820876868515, 8218.178182181735, 8225.095239523527, 8409.621567029377, 8944.37389382737, 12842.0, 13245.933770331087, 13302.93348533251, 13793.793093103812, 11283.210175287979, 11583.478743456733, 12055.397230138633, 12227.75544489123, 12330.383480826102, 11532.94233528827, 11728.530858765895, 11900.226485278428, 11932.463039163433, 11990.100742444525, 11124.332540047622, 11940.701482463008, 12088.214266072677, 12148.331841748435, 12196.146269761386, 7757.7672669819995, 7872.17342179063, 7929.682812687659, 8011.198880111942, 8448.535330556879, 7829.45193836426, 9946.900530994597, 11966.70083247891, 12151.878481215075, 12601.558945436866], "value": 9019.322100624337, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.4032716749714, 1525.5499627609856, 1650.9504714858558, 1784.2862854858063, 1790.811964743702, 8627.266682332001, 9266.351355405124, 9274.443533388001, 9283.4429934204, 9300.906990930089, 7827.373810095193, 8523.105073967237, 8794.86807697885, 8803.33974951878, 9133.680321188744, 2325.825563082767, 2353.482233908537, 2354.3290162303747, 2354.4231663242444, 2355.8233132515043, 2320.0, 2331.6852224949585, 2370.383700237933, 2371.67982322386, 2375.9168429104984, 2290.2213247495847, 2311.295055008222, 2317.3974766560646, 2377.239283429303, 2384.1059602649007, 2237.876916769577, 2267.7505474397885, 2270.14869423966, 2271.3072512883564, 2309.642005489152, 2278.7835155660187, 2280.4754906371463, 2280.988595057014, 2282.4179834142356, 2283.942901427461, 8837.116288371175, 9048.230900373475, 9069.637214511411, 9080.182783549511, 9080.591373388213, 4966.106100901836, 5086.872828179288, 5129.281900533928, 5131.358580177478, 5137.357830271216, 3148.401803657298, 3210.5344725014975, 3355.3792548378383, 3364.2934983653217, 3364.9495257571157, 1082.767205050918, 1088.7822435512928, 1095.3209010413516, 1105.4196546812907, 1122.3995162588058, 12230.449629766681, 12254.571090011896, 12257.693557660952, 12258.570950016707, 12284.631461056182, 4308.913821723548, 4512.954870451319, 4580.610648094949, 4581.312803079498, 4599.08018396322, 10356.792864142826, 10392.37645741258, 10399.688009359732, 10401.479926003669, 10411.0, 5161.045206636784, 5171.663841850268, 5174.146265866165, 5175.896482070338, 5189.42916279209, 4215.78921053946, 4321.265384884514, 4643.303504474356, 4706.435227772689, 4760.571548560646, 3870.7290489666016, 3894.2600905827803, 3968.523777146703, 3983.820728067244, 4015.5181378234797, 1178.7996040673106, 1179.5399794080315, 1191.6961174900346, 1195.6413076077142, 1202.9759404811855, 807.2290962131151, 881.3345923827469, 902.747230775384, 914.3142643017736, 968.4721826604517, 6045.244344456943, 6072.908906366408, 6074.392560743979, 6100.267967843798, 6291.622502649856, 1005.215931573374, 1015.4973288222377, 1049.2445439283704, 1061.5859814672283, 1093.1746531368924, 4149.585041495887, 4325.8269669213505, 4372.147431250875, 4403.3174857897075, 4428.269335559661, 10566.682999510027, 10919.727006824893, 10930.289531180448, 10940.617078402376, 10961.177911656658, 10599.099076578577, 10868.891311086787, 10868.945655271831, 10884.292520986111, 10895.564177432814, 7870.488418252795, 8105.027396712314, 8120.269175774209, 8123.553204573687, 8131.7560473185895, 5968.492678122235, 5985.880282394415, 6121.143039974496, 6155.815325540241, 6283.2774230962195, 9791.26565507604, 10332.896671033193, 10344.431056291674, 10350.896491034993, 10379.532921018723, 10265.12746416533, 10448.425336606255, 10534.736631583964, 10552.525136369035, 10574.524146413276, 6576.934230657824, 7135.143782746104, 7638.350740186924, 7819.804504887424, 7973.601319934096, 8270.421070525006, 8328.333733301442, 8340.04089529712, 8351.95824020876, 8352.457090289112, 7452.403807695263, 7492.0, 7500.437467190091, 7589.6584653691825, 7793.844123117619, 7495.138059123053, 7514.812129696801, 7518.0, 7604.163542010264, 7801.726939557088, 6876.312368763083, 6903.93096069053, 6937.306269373023, 6954.304569543207, 6969.337912898267, 5103.5661968733075, 5142.845714628567, 5301.761420735999, 5397.325334333208, 5417.864553386197, 5445.1832225166545, 5561.749721262634, 5591.133374326986, 5630.774769009194, 5642.943570564242], "value": 4644.030984526891, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.9017604542592, 319.9600049993751, 322.8079292820772, 324.1232466178986, 328.40722495894903, 5260.185236093551, 5262.447443018481, 5283.339582552181, 5284.947150528498, 5334.093204155293, 5181.196914478252, 5183.429822719498, 5233.084210263199, 5240.266362709214, 5243.4232234454175, 3886.80565971701, 3985.880423587297, 4017.734413659688, 4066.6136717011836, 4085.1625416789634, 4068.7558746475156, 4078.225137223933, 4078.938815917764, 4087.7751723655188, 4092.7135100542973, 4043.4945631796027, 4056.5052492716914, 4058.086930440642, 4058.1072164123843, 4088.4889388826396, 3877.922441551167, 3947.3881548359905, 4005.578019802957, 4036.2936486114813, 4084.305668036411, 3576.4635304704243, 3578.785272883635, 3588.74878758487, 3594.245208506217, 3601.4957905892934, 5073.923891141636, 5074.0570469127815, 5091.643584949055, 5127.128388174019, 5173.844784656466, 4520.615747661453, 4634.490206077328, 4639.652026098022, 4672.275797251432, 4697.53024697531, 5373.543248823855, 5389.164679474688, 5398.406175320712, 5401.810936617199, 5429.239906413066, 3747.7001839852746, 3913.119548101683, 3948.993006783252, 3950.2297052074855, 3951.565327813938, 5570.164475328693, 5582.637128586629, 5585.636933599303, 5604.271444712243, 5607.635503692247, 3954.169624378855, 3954.6638535724787, 3968.968068302262, 3972.860949866799, 3973.8609148679657, 4005.899852503711, 4013.234176962127, 4020.798960051986, 4028.8187031583648, 4034.8386064557676, 4453.953320969635, 4602.608778253819, 4608.354830323758, 4654.767261636905, 4674.392328997209, 5360.490753378423, 5437.809676661298, 5443.401225865152, 5445.346558413015, 5451.890962180734, 1377.435251546861, 1380.5030189131905, 1388.2711576422257, 1401.2573336131866, 1405.3535373727996, 873.5282947208564, 890.225503811675, 892.3619611977467, 899.3075331994328, 904.900460949295, 4626.44482662076, 4638.634296508737, 4675.13510000651, 4684.74233917131, 4782.736949467812, 4985.252212168204, 4985.4017517897355, 4992.8751781205765, 5015.695919060998, 5034.162530676277, 4381.715188512737, 4696.295555666609, 4699.765011749399, 4741.2651039089, 4746.4541577719, 2630.592258199982, 2634.3019099938147, 2650.2049385184755, 2650.734926507334, 2653.0, 5108.974455127701, 5118.436971933084, 5126.79492820283, 5150.536451719288, 5152.990333769832, 4745.081774708529, 4998.700077995332, 5055.34280543527, 5061.620378471629, 5076.96921231503, 5002.599792016557, 5003.899142188713, 5036.395632524121, 5064.037832811754, 5066.923996140061, 4847.34560834297, 4916.532929371704, 4975.875603109951, 5006.349174607278, 5020.447750747488, 4987.850364489072, 4997.800087996585, 5169.302144210634, 5576.581756368208, 5612.831615051555, 5776.913346299725, 5785.27684039495, 5805.419458054161, 5813.7674493021495, 5817.331006934257, 3864.478295430081, 3905.1603905160528, 3921.4706014687654, 3921.588233235469, 3927.626875446828, 5374.838754837361, 5448.692313844728, 5579.637323573955, 5626.2685850839125, 5670.234518339971, 5350.732463376893, 5409.269748583891, 5487.122060470306, 5489.34127904654, 5508.779648814163, 5435.809746658848, 5488.560915126701, 5491.890162196813, 5491.945080549143, 5514.393416724238, 5222.399424066281, 5394.217838413487, 5406.324209473816, 5428.212909128075, 5463.562914966714, 5485.204645326326, 5487.734797795305, 5490.752916118704, 5495.065838807493, 5503.422140675331, 5369.946300536944, 5386.326709161355, 5394.379646340721, 5396.08266594672, 5400.9459905400445], "value": 4040.053256628508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.3770327183344, 796.8605494038544, 836.1847198980994, 853.7609469348585, 865.1651156633847, 14663.413463461462, 15673.529794106194, 15731.0, 15733.0, 15749.921250393747, 14797.482088126917, 15010.399584016626, 15023.774643380359, 15042.473513427032, 15050.849491505081, 656.4157899469476, 797.8284668796193, 803.4536515169682, 819.9098099209082, 825.2325337436182, 819.1317203763999, 825.7398919340419, 828.1676914700718, 828.8673812190051, 829.713748756681, 879.9560021998907, 880.423322723617, 881.3213825354505, 881.7266647339313, 881.9426737262057, 694.2987582541618, 712.5582139073756, 877.9122087791234, 880.009988762642, 880.7445840706188, 522.0, 612.9754809807603, 624.5846512069469, 624.622103627308, 625.7059182184366, 14399.280035998263, 14434.927825360912, 14466.566003019927, 14466.927665361605, 14473.131612103307, 14249.287535623178, 14396.280185990763, 14624.780628290584, 14759.926200369036, 14817.259137043106, 11672.416379181092, 11739.0, 11881.524739010516, 11886.0, 11930.821037684442, 4419.491758447755, 4461.5984561389305, 4481.305397663367, 4551.453825540956, 4712.811487540494, 75814.24185758182, 76104.61947690281, 76348.61825690836, 76825.23174768237, 77399.22600773977, 11509.366984815748, 11547.884521154849, 11627.53489860413, 11715.472803723682, 11809.23239989398, 21441.356759297247, 21521.78478215198, 21583.136674533158, 21586.676199857015, 21609.243676471247, 8258.380621453294, 8406.0, 8470.0, 8511.787205319793, 8797.824043519095, 4662.673612847068, 4803.687760295569, 4818.055279379584, 4976.2038073908, 4989.750512474362, 3568.339857126388, 3702.074481379655, 3705.481232627434, 3705.7405981581032, 3707.4068149096015, 556.5074908705825, 658.1016911915201, 666.3403230801472, 666.5967089910631, 667.569417725574, 681.8534015186758, 682.6962001909202, 694.7116946467261, 726.9382102521239, 755.9584222867685, 9545.427274363561, 9549.61801527945, 9753.317267791186, 9906.07513988806, 9912.702618921443, 557.827073607183, 651.8272657745702, 660.6498555765452, 682.331315310998, 719.7840647805638, 4533.0, 4821.536328590632, 4985.875353116201, 5106.897862042812, 5139.152039913447, 113355.43322283335, 115621.0, 116623.0, 120685.39657301658, 120974.0, 94953.0, 98501.50749246209, 99371.50314248526, 99386.0, 100080.499597503, 52728.0, 82615.58692206621, 86132.56933715292, 87756.5612171935, 88701.0, 9565.473898935625, 9600.039996000343, 9745.463999480096, 9766.9023309766, 9812.362196457207, 50961.7451912738, 51511.742441287555, 52176.739116304176, 52821.47178528319, 52880.0, 67146.0, 71938.64030679813, 74807.0, 76380.61809691138, 77927.61036194782, 12376.319302438453, 12435.689107772378, 12702.682432939251, 12743.10798244114, 12912.354382281037, 15063.189349640994, 15481.225938703245, 15501.534953951399, 15520.5343839685, 15585.142817144711, 13334.0, 13617.114887532278, 13618.319084045956, 14494.927525362305, 14570.635734106732, 14017.929910350384, 14070.718585628434, 14199.0, 14281.857181428053, 14678.779818302948, 13313.33433328349, 13379.598612041655, 13439.126456780277, 13462.86537134616, 14168.0, 11432.599859004895, 11653.53385864556, 11679.416029198335, 11689.590864319707, 11731.88268117308, 11579.88420115788, 11836.526538938691, 11846.170768046499, 11967.581134660246, 12720.809187862375], "value": 7642.344337973023, "unit": "score"}], "name": "dromaeo_css", "value": 7244.311327189936, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0, 109.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 128.0, 177.0, 123.0, 123.0, 146.0, 121.0, 155.0, 118.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 159.0, 160.0, 160.0, 162.0, 165.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 113.0, 77.0, 76.0, 74.0, 74.0, 102.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 126.0, 85.0, 85.0, 85.0, 122.0, 84.0, 84.0, 132.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 100.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 62.0, 60.0, 61.0, 62.0, 61.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 75.0, 76.0, 75.0, 75.0, 74.0, 81.0, 80.0, 99.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 120.0, 127.0, 118.0, 120.0, 126.0, 126.0, 118.0, 194.0, 134.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 155.0, 159.0, 156.0, 160.0, 162.0, 154.0, 158.0, 159.0, 156.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 66.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1485.8999999999999, "alertThreshold": 2.0}]} 04:45:27 INFO - Return code: 0 04:45:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:45:27 INFO - # TBPL SUCCESS # 04:45:27 INFO - Running post-action listener: _resource_record_post_action 04:45:27 INFO - [mozharness: 2016-07-22 11:45:27.168595Z] Finished run-tests step (success) 04:45:27 INFO - Running post-run listener: _resource_record_post_run 04:45:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557027328; Read time: 16; Write time: 588388 04:45:27 INFO - TinderboxPrint: CPU usage
13.1% 04:45:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:45:27 INFO - TinderboxPrint: I/O write bytes / time
557,027,328 / 588,388 04:45:27 INFO - TinderboxPrint: CPU idle
8,269.7 (86.9%) 04:45:27 INFO - TinderboxPrint: CPU user
1,197.9 (12.6%) 04:45:27 INFO - TinderboxPrint: Swap in / out
0 / 0 04:45:27 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 256909312; Read time: 0; Write time: 318308 04:45:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 295546880; Read time: 0; Write time: 269460 04:45:27 INFO - Running post-run listener: _upload_blobber_files 04:45:27 INFO - Blob upload gear active. 04:45:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:45:27 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:45:27 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:45:27 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:45:28 INFO - (blobuploader) - INFO - Open directory for files ... 04:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:56 INFO - (blobuploader) - INFO - Done attempting. 04:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:57 INFO - (blobuploader) - INFO - Done attempting. 04:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:57 INFO - (blobuploader) - INFO - Done attempting. 04:45:57 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:57 INFO - Return code: 0 04:45:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76f8bbcf9c93ac998372c99622a904655dcd756406aa67f60d06530a9cdfccf7c6fb12459cf4c6ec54cd5a84135767a9caae5a67630c3571ca0f55965e5f9a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51fde90d54ef1b77f4ddea18b74fe8b498ed124ce8c1c3267ea3b31d13991ff374fe746739f0d379172b0d2dfee6c0f816eebc59c5934afcec7cd5cc6d81a66d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b05385266f4cce1ae3ed444c18382529c3479a1b0748cca08cc61bdf75ba8b4e71712ad9d668ee49aa802a98989c3abcf16fc9140fa197b2bd44af3c4833ef2"} 04:45:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:57 INFO - Contents: 04:45:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76f8bbcf9c93ac998372c99622a904655dcd756406aa67f60d06530a9cdfccf7c6fb12459cf4c6ec54cd5a84135767a9caae5a67630c3571ca0f55965e5f9a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51fde90d54ef1b77f4ddea18b74fe8b498ed124ce8c1c3267ea3b31d13991ff374fe746739f0d379172b0d2dfee6c0f816eebc59c5934afcec7cd5cc6d81a66d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b05385266f4cce1ae3ed444c18382529c3479a1b0748cca08cc61bdf75ba8b4e71712ad9d668ee49aa802a98989c3abcf16fc9140fa197b2bd44af3c4833ef2"} 04:45:57 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:57 INFO - Copying logs to upload dir... 04:45:57 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.682419 ========= 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:45:57.310009) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:45:57.313805) ========= 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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 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/76f8bbcf9c93ac998372c99622a904655dcd756406aa67f60d06530a9cdfccf7c6fb12459cf4c6ec54cd5a84135767a9caae5a67630c3571ca0f55965e5f9a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51fde90d54ef1b77f4ddea18b74fe8b498ed124ce8c1c3267ea3b31d13991ff374fe746739f0d379172b0d2dfee6c0f816eebc59c5934afcec7cd5cc6d81a66d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b05385266f4cce1ae3ed444c18382529c3479a1b0748cca08cc61bdf75ba8b4e71712ad9d668ee49aa802a98989c3abcf16fc9140fa197b2bd44af3c4833ef2"} 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.011274 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/76f8bbcf9c93ac998372c99622a904655dcd756406aa67f60d06530a9cdfccf7c6fb12459cf4c6ec54cd5a84135767a9caae5a67630c3571ca0f55965e5f9a59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/51fde90d54ef1b77f4ddea18b74fe8b498ed124ce8c1c3267ea3b31d13991ff374fe746739f0d379172b0d2dfee6c0f816eebc59c5934afcec7cd5cc6d81a66d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b05385266f4cce1ae3ed444c18382529c3479a1b0748cca08cc61bdf75ba8b4e71712ad9d668ee49aa802a98989c3abcf16fc9140fa197b2bd44af3c4833ef2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:45:57.339412) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:45:57.339760) ========= 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=5bd038846534b2fdfa68eeae000001e2-1469186700.250096-1806163945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:45:57.425978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:45:57.426344) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:45:57.426690) ========= ========= Total master_lag: 0.17 =========