builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1470623128.65 results: success (0) buildid: 20160807163001 builduid: 32b0ee2e4876498bba98f51e85b3bc1c revision: 8db356a0d5b5e3af4aeba8fc20baf45289acca92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.647407) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.648024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.648492) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.664934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.665548) ========= 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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 19:25:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-08-07 19:25:29 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149300 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.839235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.839561) ========= 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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018458 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.895567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:28.896106) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8db356a0d5b5e3af4aeba8fc20baf45289acca92 --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 8db356a0d5b5e3af4aeba8fc20baf45289acca92 --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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 19:25:29,975 truncating revision to first 12 chars 2016-08-07 19:25:29,975 Setting DEBUG logging. 2016-08-07 19:25:29,975 attempt 1/10 2016-08-07 19:25:29,975 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/8db356a0d5b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 19:25:30,276 unpacking tar archive at: mozilla-inbound-8db356a0d5b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651879 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:29.561614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:29.561960) ========= script_repo_revision: 8db356a0d5b5e3af4aeba8fc20baf45289acca92 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:29.562387) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:29.562687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 19:25:29.578112) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-08-07 19:25:29.578423) ========= /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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:25:30 INFO - MultiFileLogger online at 20160807 19:25:30 in /builds/slave/test 19:25:30 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 19:25:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:25:30 INFO - {'append_to_log': False, 19:25:30 INFO - 'base_work_dir': '/builds/slave/test', 19:25:30 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:25:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:25:30 INFO - 'branch': 'Mozilla-Inbound', 19:25:30 INFO - 'buildbot_json_path': 'buildprops.json', 19:25:30 INFO - 'config_files': ('talos/linux_config.py',), 19:25:30 INFO - 'default_actions': ('clobber', 19:25:30 INFO - 'read-buildbot-config', 19:25:30 INFO - 'download-and-extract', 19:25:30 INFO - 'populate-webroot', 19:25:30 INFO - 'create-virtualenv', 19:25:30 INFO - 'install', 19:25:30 INFO - 'run-tests'), 19:25:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:25:30 INFO - 'download_minidump_stackwalk': True, 19:25:30 INFO - 'download_symbols': 'ondemand', 19:25:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:25:30 INFO - 'tooltool.py': '/tools/tooltool.py', 19:25:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:25:30 INFO - '/tools/misc-python/virtualenv.py')}, 19:25:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:25:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:25:30 INFO - 'installer_path': 'installer.exe', 19:25:30 INFO - 'log_level': 'info', 19:25:30 INFO - 'log_name': 'talos', 19:25:30 INFO - 'log_to_console': True, 19:25:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:25:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:25:30 INFO - 'opt_config_files': (), 19:25:30 INFO - 'pip_index': False, 19:25:30 INFO - 'sps_profile': False, 19:25:30 INFO - 'sps_profile_interval': 0, 19:25:30 INFO - 'suite': 'dromaeojs', 19:25:30 INFO - 'system_bits': '32', 19:25:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:25:30 INFO - 'title': 'talos-linux64-ix-017', 19:25:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:25:30 INFO - 'use_talos_json': True, 19:25:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:25:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:25:30 INFO - 'work_dir': 'build'} 19:25:30 INFO - [mozharness: 2016-08-08 02:25:30.746450Z] Running clobber step. 19:25:30 INFO - Running pre-action listener: _resource_record_pre_action 19:25:30 INFO - Running main action method: clobber 19:25:30 INFO - rmtree: /builds/slave/test/build 19:25:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:25:31 INFO - Running post-action listener: _resource_record_post_action 19:25:31 INFO - [mozharness: 2016-08-08 02:25:31.202399Z] Finished clobber step (success) 19:25:31 INFO - [mozharness: 2016-08-08 02:25:31.202500Z] Running read-buildbot-config step. 19:25:31 INFO - Running pre-action listener: _resource_record_pre_action 19:25:31 INFO - Running main action method: read_buildbot_config 19:25:31 INFO - Using buildbot properties: 19:25:31 INFO - { 19:25:31 INFO - "project": "", 19:25:31 INFO - "product": "firefox", 19:25:31 INFO - "script_repo_revision": "production", 19:25:31 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:25:31 INFO - "repository": "", 19:25:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:25:31 INFO - "buildid": "20160807163001", 19:25:31 INFO - "pgo_build": "True", 19:25:31 INFO - "basedir": "/builds/slave/test", 19:25:31 INFO - "buildnumber": 2213, 19:25:31 INFO - "slavename": "talos-linux64-ix-017", 19:25:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:25:31 INFO - "platform": "ubuntu64_hw", 19:25:31 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:25:31 INFO - "branch": "mozilla-inbound", 19:25:31 INFO - "repo_path": "integration/mozilla-inbound", 19:25:31 INFO - "revision": "8db356a0d5b5e3af4aeba8fc20baf45289acca92", 19:25:31 INFO - "stage_platform": "linux64-pgo", 19:25:31 INFO - "builduid": "32b0ee2e4876498bba98f51e85b3bc1c", 19:25:31 INFO - "slavebuilddir": "test" 19:25:31 INFO - } 19:25:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:25:31 INFO - Running post-action listener: _resource_record_post_action 19:25:31 INFO - [mozharness: 2016-08-08 02:25:31.204920Z] Finished read-buildbot-config step (success) 19:25:31 INFO - [mozharness: 2016-08-08 02:25:31.205008Z] Running download-and-extract step. 19:25:31 INFO - Running pre-action listener: _resource_record_pre_action 19:25:31 INFO - Running main action method: download_and_extract 19:25:31 INFO - mkdir: /builds/slave/test/build/tests 19:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:31 INFO - trying https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:25:31 INFO - Downloaded 1361 bytes. 19:25:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:25:31 INFO - Using the following test package requirements: 19:25:31 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:25:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:25:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:25:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'jsshell-linux-x86_64.zip'], 19:25:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:25:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:25:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:25:31 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:25:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:25:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:25:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:25:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:31 INFO - trying https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:25:32 INFO - Downloaded 21829423 bytes. 19:25:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:25:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:25:33 INFO - Return code: 0 19:25:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:33 INFO - trying https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:25:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:25:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:25:34 INFO - Downloaded 21829423 bytes. 19:25:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:25:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:25:36 INFO - Return code: 0 19:25:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:36 INFO - trying https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:25:36 INFO - Downloading https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:25:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:25:37 INFO - Downloaded 14413723 bytes. 19:25:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:25:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:25:37 INFO - Return code: 0 19:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:37 INFO - trying https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:25:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:25:38 INFO - Downloaded 59967389 bytes. 19:25:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:25:38 INFO - mkdir: /builds/slave/test/properties 19:25:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:25:38 INFO - Writing to file /builds/slave/test/properties/build_url 19:25:38 INFO - Contents: 19:25:38 INFO - build_url:https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:25:39 INFO - Running post-action listener: _resource_record_post_action 19:25:39 INFO - Running post-action listener: set_extra_try_arguments 19:25:39 INFO - [mozharness: 2016-08-08 02:25:39.324524Z] Finished download-and-extract step (success) 19:25:39 INFO - [mozharness: 2016-08-08 02:25:39.324754Z] Running populate-webroot step. 19:25:39 INFO - Running pre-action listener: _resource_record_pre_action 19:25:39 INFO - Running main action method: populate_webroot 19:25:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:25:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:25:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:25:39 INFO - '--tppagecycles', 19:25:39 INFO - '7'], 19:25:39 INFO - 'tests': ['tsvgm']}}, 19:25:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:25:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:25:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:25:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:25:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:25:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:25:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['damp', 'tps']}, 19:25:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'tests': ['damp', 'tps']}, 19:25:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['dromaeo_dom']}, 19:25:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:25:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['basic_compositor_video']}, 19:25:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:25:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'other-e10s': {'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['a11yr', 19:25:39 INFO - 'ts_paint', 19:25:39 INFO - 'tpaint', 19:25:39 INFO - 'sessionrestore', 19:25:39 INFO - 'sessionrestore_no_auto_restore', 19:25:39 INFO - 'tabpaint']}, 19:25:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['tsvgx', 19:25:39 INFO - 'tsvgr_opacity', 19:25:39 INFO - 'tart', 19:25:39 INFO - 'tscrollx', 19:25:39 INFO - 'cart']}, 19:25:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:25:39 INFO - 'tsvgr_opacity', 19:25:39 INFO - 'tart', 19:25:39 INFO - 'tscrollx', 19:25:39 INFO - 'cart']}, 19:25:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'talos_options': ['--disable-e10s'], 19:25:39 INFO - 'tests': ['tp5o']}, 19:25:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'tests': ['tp5o']}, 19:25:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'talos_options': ['--disable-e10s', 19:25:39 INFO - '--xperf_path', 19:25:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:25:39 INFO - 'tests': ['tp5n']}, 19:25:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:25:39 INFO - 'talos_options': ['--xperf_path', 19:25:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:25:39 INFO - 'tests': ['tp5n']}}, 19:25:39 INFO - 'talos.zip': {'path': '', 19:25:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:25:39 INFO - Running post-action listener: _resource_record_post_action 19:25:39 INFO - [mozharness: 2016-08-08 02:25:39.345873Z] Finished populate-webroot step (success) 19:25:39 INFO - [mozharness: 2016-08-08 02:25:39.345962Z] Running create-virtualenv step. 19:25:39 INFO - Running pre-action listener: _resource_record_pre_action 19:25:39 INFO - Running main action method: create_virtualenv 19:25:39 INFO - Creating virtualenv /builds/slave/test/build/venv 19:25:39 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 19:25:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:25:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:25:39 INFO - Using real prefix '/usr' 19:25:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:25:40 INFO - Installing distribute.............................................................................................................................................................................................done. 19:25:41 INFO - Installing pip.................done. 19:25:42 INFO - Return code: 0 19:25:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:42 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:42 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 19:25:42 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 19:25:42 INFO - Using env: {'DISPLAY': ':0', 19:25:42 INFO - 'HOME': '/home/cltbld', 19:25:42 INFO - 'LANG': 'en_US.UTF-8', 19:25:42 INFO - 'LANGUAGE': 'en_US:en', 19:25:42 INFO - 'LOGNAME': 'cltbld', 19:25:42 INFO - 'MAIL': '/var/mail/cltbld', 19:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:42 INFO - 'MOZ_NO_REMOTE': '1', 19:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:42 INFO - 'NO_EM_RESTART': '1', 19:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:42 INFO - 'PWD': '/builds/slave/test', 19:25:42 INFO - 'SHELL': '/bin/bash', 19:25:42 INFO - 'SHLVL': '1', 19:25:42 INFO - 'TERM': 'linux', 19:25:42 INFO - 'TMOUT': '86400', 19:25:42 INFO - 'USER': 'cltbld', 19:25:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:42 INFO - '_': '/tools/buildbot/bin/python'} 19:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:42 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 19:25:42 INFO - Cleaning up... 19:25:42 INFO - Return code: 0 19:25:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:42 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:42 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 19:25:42 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 19:25:42 INFO - Using env: {'DISPLAY': ':0', 19:25:42 INFO - 'HOME': '/home/cltbld', 19:25:42 INFO - 'LANG': 'en_US.UTF-8', 19:25:42 INFO - 'LANGUAGE': 'en_US:en', 19:25:42 INFO - 'LOGNAME': 'cltbld', 19:25:42 INFO - 'MAIL': '/var/mail/cltbld', 19:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:42 INFO - 'MOZ_NO_REMOTE': '1', 19:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:42 INFO - 'NO_EM_RESTART': '1', 19:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:42 INFO - 'PWD': '/builds/slave/test', 19:25:42 INFO - 'SHELL': '/bin/bash', 19:25:42 INFO - 'SHLVL': '1', 19:25:42 INFO - 'TERM': 'linux', 19:25:42 INFO - 'TMOUT': '86400', 19:25:42 INFO - 'USER': 'cltbld', 19:25:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:42 INFO - '_': '/tools/buildbot/bin/python'} 19:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:42 INFO - Downloading/unpacking psutil>=3.1.1 19:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:25:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:25:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:25:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:25:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:25:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:25:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:25:44 INFO - Installing collected packages: psutil 19:25:44 INFO - Running setup.py install for psutil 19:25:44 INFO - building 'psutil._psutil_linux' extension 19:25:44 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 19:25:44 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 19:25:44 INFO - building 'psutil._psutil_posix' extension 19:25:44 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 19:25:44 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 19:25:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:25:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:25:44 INFO - Successfully installed psutil 19:25:44 INFO - Cleaning up... 19:25:44 INFO - Return code: 0 19:25:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:44 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:44 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 19:25:44 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 19:25:44 INFO - Using env: {'DISPLAY': ':0', 19:25:44 INFO - 'HOME': '/home/cltbld', 19:25:44 INFO - 'LANG': 'en_US.UTF-8', 19:25:44 INFO - 'LANGUAGE': 'en_US:en', 19:25:44 INFO - 'LOGNAME': 'cltbld', 19:25:44 INFO - 'MAIL': '/var/mail/cltbld', 19:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:44 INFO - 'MOZ_NO_REMOTE': '1', 19:25:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:44 INFO - 'NO_EM_RESTART': '1', 19:25:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:44 INFO - 'PWD': '/builds/slave/test', 19:25:44 INFO - 'SHELL': '/bin/bash', 19:25:44 INFO - 'SHLVL': '1', 19:25:44 INFO - 'TERM': 'linux', 19:25:44 INFO - 'TMOUT': '86400', 19:25:44 INFO - 'USER': 'cltbld', 19:25:44 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:44 INFO - '_': '/tools/buildbot/bin/python'} 19:25:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:25:45 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 19:25:45 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 19:25:45 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 19:25:45 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 19:25:45 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 19:25:45 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 19:25:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:25:47 INFO - Installing collected packages: mozsystemmonitor 19:25:47 INFO - Running setup.py install for mozsystemmonitor 19:25:47 INFO - Successfully installed mozsystemmonitor 19:25:47 INFO - Cleaning up... 19:25:47 INFO - Return code: 0 19:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:47 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:47 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 19:25:47 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 19:25:47 INFO - Using env: {'DISPLAY': ':0', 19:25:47 INFO - 'HOME': '/home/cltbld', 19:25:47 INFO - 'LANG': 'en_US.UTF-8', 19:25:47 INFO - 'LANGUAGE': 'en_US:en', 19:25:47 INFO - 'LOGNAME': 'cltbld', 19:25:47 INFO - 'MAIL': '/var/mail/cltbld', 19:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:47 INFO - 'MOZ_NO_REMOTE': '1', 19:25:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:47 INFO - 'NO_EM_RESTART': '1', 19:25:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:47 INFO - 'PWD': '/builds/slave/test', 19:25:47 INFO - 'SHELL': '/bin/bash', 19:25:47 INFO - 'SHLVL': '1', 19:25:47 INFO - 'TERM': 'linux', 19:25:47 INFO - 'TMOUT': '86400', 19:25:47 INFO - 'USER': 'cltbld', 19:25:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:47 INFO - '_': '/tools/buildbot/bin/python'} 19:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:47 INFO - Downloading/unpacking jsonschema==2.5.1 19:25:47 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 19:25:47 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 19:25:47 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 19:25:47 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 19:25:47 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 19:25:47 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 19:25:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:25:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:25:49 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 19:25:49 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 19:25:49 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 19:25:49 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 19:25:49 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 19:25:49 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 19:25:49 INFO - Downloading functools32-3.2.3-2.tar.gz 19:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:25:49 INFO - warning: no files found matching '*.txt' 19:25:49 INFO - no previously-included directories found matching 'build' 19:25:49 INFO - no previously-included directories found matching 'dist' 19:25:49 INFO - no previously-included directories found matching '.git*' 19:25:49 INFO - Installing collected packages: jsonschema, functools32 19:25:49 INFO - Running setup.py install for functools32 19:25:49 INFO - warning: no files found matching '*.txt' 19:25:49 INFO - no previously-included directories found matching 'build' 19:25:49 INFO - no previously-included directories found matching 'dist' 19:25:49 INFO - no previously-included directories found matching '.git*' 19:25:49 INFO - Successfully installed jsonschema functools32 19:25:49 INFO - Cleaning up... 19:25:49 INFO - Return code: 0 19:25:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:25:49 INFO - Using env: {'DISPLAY': ':0', 19:25:49 INFO - 'HOME': '/home/cltbld', 19:25:49 INFO - 'LANG': 'en_US.UTF-8', 19:25:49 INFO - 'LANGUAGE': 'en_US:en', 19:25:49 INFO - 'LOGNAME': 'cltbld', 19:25:49 INFO - 'MAIL': '/var/mail/cltbld', 19:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:49 INFO - 'MOZ_NO_REMOTE': '1', 19:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:49 INFO - 'NO_EM_RESTART': '1', 19:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:49 INFO - 'PWD': '/builds/slave/test', 19:25:49 INFO - 'SHELL': '/bin/bash', 19:25:49 INFO - 'SHLVL': '1', 19:25:49 INFO - 'TERM': 'linux', 19:25:49 INFO - 'TMOUT': '86400', 19:25:49 INFO - 'USER': 'cltbld', 19:25:49 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:49 INFO - '_': '/tools/buildbot/bin/python'} 19:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:25:50 INFO - Cleaning up... 19:25:50 INFO - Return code: 0 19:25:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:50 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:50 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 19:25:50 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 19:25:50 INFO - Using env: {'DISPLAY': ':0', 19:25:50 INFO - 'HOME': '/home/cltbld', 19:25:50 INFO - 'LANG': 'en_US.UTF-8', 19:25:50 INFO - 'LANGUAGE': 'en_US:en', 19:25:50 INFO - 'LOGNAME': 'cltbld', 19:25:50 INFO - 'MAIL': '/var/mail/cltbld', 19:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:50 INFO - 'MOZ_NO_REMOTE': '1', 19:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:50 INFO - 'NO_EM_RESTART': '1', 19:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:50 INFO - 'PWD': '/builds/slave/test', 19:25:50 INFO - 'SHELL': '/bin/bash', 19:25:50 INFO - 'SHLVL': '1', 19:25:50 INFO - 'TERM': 'linux', 19:25:50 INFO - 'TMOUT': '86400', 19:25:50 INFO - 'USER': 'cltbld', 19:25:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:50 INFO - '_': '/tools/buildbot/bin/python'} 19:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:50 INFO - Downloading/unpacking blobuploader==1.2.4 19:25:50 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 19:25:50 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 19:25:50 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 19:25:50 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 19:25:50 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 19:25:50 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 19:25:52 INFO - Downloading blobuploader-1.2.4.tar.gz 19:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:25:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:25:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 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 19:25:52 INFO - Downloading docopt-0.6.1.tar.gz 19:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:25:52 INFO - Installing collected packages: blobuploader, requests, docopt 19:25:52 INFO - Running setup.py install for blobuploader 19:25:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:25:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:25:52 INFO - Running setup.py install for requests 19:25:53 INFO - Running setup.py install for docopt 19:25:53 INFO - Successfully installed blobuploader requests docopt 19:25:53 INFO - Cleaning up... 19:25:53 INFO - Return code: 0 19:25:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:53 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:53 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 19:25:53 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 19:25:53 INFO - Using env: {'DISPLAY': ':0', 19:25:53 INFO - 'HOME': '/home/cltbld', 19:25:53 INFO - 'LANG': 'en_US.UTF-8', 19:25:53 INFO - 'LANGUAGE': 'en_US:en', 19:25:53 INFO - 'LOGNAME': 'cltbld', 19:25:53 INFO - 'MAIL': '/var/mail/cltbld', 19:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:53 INFO - 'MOZ_NO_REMOTE': '1', 19:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:53 INFO - 'NO_EM_RESTART': '1', 19:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:53 INFO - 'PWD': '/builds/slave/test', 19:25:53 INFO - 'SHELL': '/bin/bash', 19:25:53 INFO - 'SHLVL': '1', 19:25:53 INFO - 'TERM': 'linux', 19:25:53 INFO - 'TMOUT': '86400', 19:25:53 INFO - 'USER': 'cltbld', 19:25:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:53 INFO - '_': '/tools/buildbot/bin/python'} 19:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:25:53 INFO - Running setup.py (path:/tmp/pip-2x8UX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:25:53 INFO - Running setup.py (path:/tmp/pip-YgBKJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:25:53 INFO - Running setup.py (path:/tmp/pip-1wRwhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:25:53 INFO - Running setup.py (path:/tmp/pip-MWXWzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:25:53 INFO - Running setup.py (path:/tmp/pip-qDB4Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:25:54 INFO - Running setup.py (path:/tmp/pip-yyF7BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:25:54 INFO - Running setup.py (path:/tmp/pip-g0BEfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:25:54 INFO - Running setup.py (path:/tmp/pip-ITBIKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:25:54 INFO - Running setup.py (path:/tmp/pip-46e4RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:25:54 INFO - Running setup.py (path:/tmp/pip-w2H76G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:25:54 INFO - Running setup.py (path:/tmp/pip-W2g1Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:25:54 INFO - Running setup.py (path:/tmp/pip-3MIsU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:25:54 INFO - Running setup.py (path:/tmp/pip-6vCkeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:25:54 INFO - Running setup.py (path:/tmp/pip-qdgWFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:25:54 INFO - Running setup.py (path:/tmp/pip-gXR4F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:25:54 INFO - Running setup.py (path:/tmp/pip-KCC0m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:25:54 INFO - Running setup.py (path:/tmp/pip-Y8PZZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:25:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:25:54 INFO - Running setup.py install for manifestparser 19:25:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for mozcrash 19:25:55 INFO - Running setup.py install for mozdebug 19:25:55 INFO - Running setup.py install for mozdevice 19:25:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for mozfile 19:25:55 INFO - Running setup.py install for mozhttpd 19:25:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for mozinfo 19:25:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for mozInstall 19:25:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for mozleak 19:25:55 INFO - Running setup.py install for mozlog 19:25:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:25:55 INFO - Running setup.py install for moznetwork 19:25:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Running setup.py install for mozprocess 19:25:56 INFO - Running setup.py install for mozprofile 19:25:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Running setup.py install for mozrunner 19:25:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Running setup.py install for mozscreenshot 19:25:56 INFO - Running setup.py install for moztest 19:25:56 INFO - Running setup.py install for mozversion 19:25:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:25:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:25:56 INFO - Cleaning up... 19:25:56 INFO - Return code: 0 19:25:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:25:56 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:25:56 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 19:25:56 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 19:25:56 INFO - Using env: {'DISPLAY': ':0', 19:25:56 INFO - 'HOME': '/home/cltbld', 19:25:56 INFO - 'LANG': 'en_US.UTF-8', 19:25:56 INFO - 'LANGUAGE': 'en_US:en', 19:25:56 INFO - 'LOGNAME': 'cltbld', 19:25:56 INFO - 'MAIL': '/var/mail/cltbld', 19:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:56 INFO - 'MOZ_NO_REMOTE': '1', 19:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:56 INFO - 'NO_EM_RESTART': '1', 19:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:56 INFO - 'PWD': '/builds/slave/test', 19:25:56 INFO - 'SHELL': '/bin/bash', 19:25:56 INFO - 'SHLVL': '1', 19:25:56 INFO - 'TERM': 'linux', 19:25:56 INFO - 'TMOUT': '86400', 19:25:56 INFO - 'USER': 'cltbld', 19:25:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:56 INFO - '_': '/tools/buildbot/bin/python'} 19:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:25:56 INFO - Running setup.py (path:/tmp/pip-e9FPNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:25:57 INFO - Running setup.py (path:/tmp/pip-bSBMtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:25:57 INFO - Running setup.py (path:/tmp/pip-7xlv_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:25:57 INFO - Running setup.py (path:/tmp/pip-tbhqk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:25:57 INFO - Running setup.py (path:/tmp/pip-6vXZ7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:25:57 INFO - Running setup.py (path:/tmp/pip-NTRZJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:25:57 INFO - Running setup.py (path:/tmp/pip-y4F3M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:25:57 INFO - Running setup.py (path:/tmp/pip-DNoMc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:25:57 INFO - Running setup.py (path:/tmp/pip-In6VuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:25:57 INFO - Running setup.py (path:/tmp/pip-Y5kATj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:25:57 INFO - Running setup.py (path:/tmp/pip-Gev8u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:25:57 INFO - Running setup.py (path:/tmp/pip-5MU8_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:25:57 INFO - Running setup.py (path:/tmp/pip-XT1DZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:25:57 INFO - Running setup.py (path:/tmp/pip-MGCSMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:25:57 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)) 19:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:25:57 INFO - Running setup.py (path:/tmp/pip-W6uLkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:25:58 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)) 19:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:25:58 INFO - Running setup.py (path:/tmp/pip-vyfp6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:25:58 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)) 19:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:25:58 INFO - Running setup.py (path:/tmp/pip-wDrHqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:25:58 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)) 19:25:58 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)) 19:25:58 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)) 19:25:58 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)) 19:25:58 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)) 19:25:58 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)) 19:25:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:25:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:25:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:00 INFO - Downloading blessings-1.6.tar.gz 19:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:00 INFO - Installing collected packages: blessings 19:26:00 INFO - Running setup.py install for blessings 19:26:00 INFO - Successfully installed blessings 19:26:00 INFO - Cleaning up... 19:26:00 INFO - Return code: 0 19:26:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:00 INFO - Reading from file tmpfile_stdout 19:26:00 INFO - Current package versions: 19:26:00 INFO - argparse == 1.2.1 19:26:00 INFO - blessings == 1.6 19:26:00 INFO - blobuploader == 1.2.4 19:26:00 INFO - docopt == 0.6.1 19:26:00 INFO - functools32 == 3.2.3-2 19:26:00 INFO - jsonschema == 2.5.1 19:26:00 INFO - manifestparser == 1.1 19:26:00 INFO - mozInstall == 1.12 19:26:00 INFO - mozcrash == 0.17 19:26:00 INFO - mozdebug == 0.1 19:26:00 INFO - mozdevice == 0.48 19:26:00 INFO - mozfile == 1.2 19:26:00 INFO - mozhttpd == 0.7 19:26:00 INFO - mozinfo == 0.9 19:26:00 INFO - mozleak == 0.1 19:26:00 INFO - mozlog == 3.3 19:26:00 INFO - moznetwork == 0.27 19:26:00 INFO - mozprocess == 0.23 19:26:00 INFO - mozprofile == 0.28 19:26:00 INFO - mozrunner == 6.12 19:26:00 INFO - mozscreenshot == 0.1 19:26:00 INFO - mozsystemmonitor == 0.3 19:26:00 INFO - moztest == 0.7 19:26:00 INFO - mozversion == 1.4 19:26:00 INFO - psutil == 3.1.1 19:26:00 INFO - requests == 1.2.3 19:26:00 INFO - wsgiref == 0.1.2 19:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:00 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 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:26:00 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 19:26:00 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 19:26:00 INFO - Using env: {'DISPLAY': ':0', 19:26:00 INFO - 'HOME': '/home/cltbld', 19:26:00 INFO - 'LANG': 'en_US.UTF-8', 19:26:00 INFO - 'LANGUAGE': 'en_US:en', 19:26:00 INFO - 'LOGNAME': 'cltbld', 19:26:00 INFO - 'MAIL': '/var/mail/cltbld', 19:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:00 INFO - 'MOZ_NO_REMOTE': '1', 19:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:00 INFO - 'NO_EM_RESTART': '1', 19:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:00 INFO - 'PWD': '/builds/slave/test', 19:26:00 INFO - 'SHELL': '/bin/bash', 19:26:00 INFO - 'SHLVL': '1', 19:26:00 INFO - 'TERM': 'linux', 19:26:00 INFO - 'TMOUT': '86400', 19:26:00 INFO - 'USER': 'cltbld', 19:26:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:00 INFO - '_': '/tools/buildbot/bin/python'} 19:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 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)) 19:26:00 INFO - Cleaning up... 19:26:00 INFO - Return code: 0 19:26:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2264180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, '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': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', '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 19:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:26:00 INFO - Using env: {'DISPLAY': ':0', 19:26:00 INFO - 'HOME': '/home/cltbld', 19:26:00 INFO - 'LANG': 'en_US.UTF-8', 19:26:00 INFO - 'LANGUAGE': 'en_US:en', 19:26:00 INFO - 'LOGNAME': 'cltbld', 19:26:00 INFO - 'MAIL': '/var/mail/cltbld', 19:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:00 INFO - 'MOZ_NO_REMOTE': '1', 19:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:00 INFO - 'NO_EM_RESTART': '1', 19:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:00 INFO - 'PWD': '/builds/slave/test', 19:26:00 INFO - 'SHELL': '/bin/bash', 19:26:00 INFO - 'SHLVL': '1', 19:26:00 INFO - 'TERM': 'linux', 19:26:00 INFO - 'TMOUT': '86400', 19:26:00 INFO - 'USER': 'cltbld', 19:26:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:00 INFO - '_': '/tools/buildbot/bin/python'} 19:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:00 INFO - Cleaning up... 19:26:00 INFO - Return code: 0 19:26:00 INFO - Running post-action listener: _resource_record_post_action 19:26:00 INFO - Running post-action listener: _start_resource_monitoring 19:26:01 INFO - Starting resource monitoring. 19:26:01 INFO - [mozharness: 2016-08-08 02:26:01.024471Z] Finished create-virtualenv step (success) 19:26:01 INFO - [mozharness: 2016-08-08 02:26:01.024951Z] Running install step. 19:26:01 INFO - Running pre-action listener: _resource_record_pre_action 19:26:01 INFO - Running main action method: install 19:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:01 INFO - Reading from file tmpfile_stdout 19:26:01 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:01 INFO - Reading from file tmpfile_stdout 19:26:01 INFO - Output received: 19:26:01 INFO - Usage: mozinstall [options] installer 19:26:01 INFO - Options: 19:26:01 INFO - -h, --help show this help message and exit 19:26:01 INFO - -d DEST, --destination=DEST 19:26:01 INFO - Directory to install application into. [default: 19:26:01 INFO - "/builds/slave/test"] 19:26:01 INFO - --app=APP Application being installed. [default: firefox] 19:26:01 INFO - mkdir: /builds/slave/test/build/application 19:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:26:18 INFO - Reading from file tmpfile_stdout 19:26:18 INFO - Output received: 19:26:18 INFO - /builds/slave/test/build/application/firefox/firefox 19:26:18 INFO - Running post-action listener: _resource_record_post_action 19:26:18 INFO - [mozharness: 2016-08-08 02:26:18.375029Z] Finished install step (success) 19:26:18 INFO - [mozharness: 2016-08-08 02:26:18.375277Z] Running run-tests step. 19:26:18 INFO - Running pre-action listener: _resource_record_pre_action 19:26:18 INFO - Running main action method: run_tests 19:26:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:26:18 INFO - Python 2.7.3 19:26:18 INFO - Return code: 0 19:26:18 INFO - grabbing minidump binary from tooltool 19:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:18 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 0x22623b0>, '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 0x22632b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2263740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:26:18 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 19:26:18 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 19:26:18 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 19:26:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:26:18 INFO - Return code: 0 19:26:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:26:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:26:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:26:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:26:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:26:18 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/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 19:26:18 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/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 19:26:18 INFO - Using env: {'DISPLAY': ':0', 19:26:18 INFO - 'HOME': '/home/cltbld', 19:26:18 INFO - 'LANG': 'en_US.UTF-8', 19:26:18 INFO - 'LANGUAGE': 'en_US:en', 19:26:18 INFO - 'LOGNAME': 'cltbld', 19:26:18 INFO - 'MAIL': '/var/mail/cltbld', 19:26:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:26:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:18 INFO - 'MOZ_NO_REMOTE': '1', 19:26:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:26:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:18 INFO - 'NO_EM_RESTART': '1', 19:26:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:18 INFO - 'PWD': '/builds/slave/test', 19:26:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:26:18 INFO - 'SHELL': '/bin/bash', 19:26:18 INFO - 'SHLVL': '1', 19:26:18 INFO - 'TERM': 'linux', 19:26:18 INFO - 'TMOUT': '86400', 19:26:18 INFO - 'USER': 'cltbld', 19:26:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837', 19:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:18 INFO - '_': '/tools/buildbot/bin/python'} 19:26:18 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/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 19:26:18 INFO - mozversion application_buildid: 20160807163001 19:26:18 INFO - mozversion application_changeset: 8db356a0d5b5e3af4aeba8fc20baf45289acca92 19:26:18 INFO - mozversion application_display_name: Nightly 19:26:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:26:18 INFO - mozversion application_name: Firefox 19:26:18 INFO - mozversion application_remotingname: firefox 19:26:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:26:18 INFO - mozversion application_vendor: Mozilla 19:26:18 INFO - mozversion application_version: 51.0a1 19:26:18 INFO - mozversion platform_buildid: 20160807163001 19:26:18 INFO - mozversion platform_changeset: 8db356a0d5b5e3af4aeba8fc20baf45289acca92 19:26:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:26:18 INFO - mozversion platform_version: 51.0a1 19:26:18 INFO - using testdate: 1470623178 19:26:18 INFO - actual date: 1470623178 19:26:18 INFO - starting webserver on 'localhost:36493' 19:26:18 INFO - SUITE-START | Running 2 tests 19:26:18 INFO - TEST-START | dromaeo_css 19:26:18 INFO - operating with platform_type : linux_ 19:26:18 INFO - Initialising browser for dromaeo_css test... 19:26:18 INFO - TEST-INFO | started process 4768 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLpmHKW/profile http://localhost:36493/getInfo.html) 19:26:22 INFO - PROCESS | 4768 | __metrics Screen width/height:1600/1200 19:26:22 INFO - PROCESS | 4768 | colorDepth:24 19:26:22 INFO - PROCESS | 4768 | Browser inner width/height: 1024/697 19:26:22 INFO - PROCESS | 4768 | __metrics 19:26:23 INFO - PROCESS | 4768 | 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" 19:26:23 INFO - PROCESS | 4768 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:26:23 INFO - TEST-INFO | 4768: exit 0 19:26:23 INFO - Browser initialized. 19:26:23 INFO - Running cycle 1/1 for dromaeo_css test... 19:26:23 INFO - TEST-INFO | started process 4851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLpmHKW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:26:35 INFO - PROCESS | 4851 | RSS: Main: 156446720 19:26:35 INFO - PROCESS | 4851 | 19:27:27 INFO - PROCESS | 4851 | 1470623247225 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] 19:29:36 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36493/tests/dromaeo/cssquery-ext.html) 19:29:37 INFO - PROCESS | 4851 | RSS: Main: 258936832 19:29:37 INFO - PROCESS | 4851 | 19:32:39 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-ext.html (next: http://localhost:36493/tests/dromaeo/cssquery-jquery.html) 19:32:40 INFO - PROCESS | 4851 | RSS: Main: 246374400 19:32:40 INFO - PROCESS | 4851 | 19:35:42 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36493/tests/dromaeo/cssquery-mootools.html) 19:35:42 INFO - PROCESS | 4851 | RSS: Main: 185282560 19:35:42 INFO - PROCESS | 4851 | 19:38:45 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36493/tests/dromaeo/cssquery-prototype.html) 19:38:45 INFO - PROCESS | 4851 | RSS: Main: 227090432 19:38:45 INFO - PROCESS | 4851 | 19:41:48 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36493/tests/dromaeo/cssquery-yui.html) 19:41:48 INFO - PROCESS | 4851 | RSS: Main: 184279040 19:41:48 INFO - PROCESS | 4851 | 19:44:51 INFO - PROCESS | 4851 | Cycle 1(1): loaded http://localhost:36493/tests/dromaeo/cssquery-yui.html (next: http://localhost:36493/tests/dromaeo/cssquery-dojo.html) 19:44:52 INFO - PROCESS | 4851 | RSS: Main: 277983232 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | __start_tp_report 19:44:52 INFO - PROCESS | 4851 | _x_x_mozilla_page_load 19:44:52 INFO - PROCESS | 4851 | _x_x_mozilla_page_load_details 19:44:52 INFO - PROCESS | 4851 | |i|pagename|runs| 19:44:52 INFO - PROCESS | 4851 | |0;dojo.html;3018.33596608746;3022.2595464111296;3059.0669845697066;3065.2643365592266;3070.8771649134037;5082.03441346144;5156.690598564089;5276.26132341472;5325.121354976424;5335.9199612005805;5261.289725887004;5270.262163297141;5310.3893052299;5336.8132115375965;5354.892902141954;4686.367340409052;4721.9055618887605;4765.666403351766;4768.213244814601;4899.044686286158;4824.469308376076;4852.126617208901;4853.757312134397;4866.294387313837;4889.657723959324;4642.907141857161;4690.7654617269;4691.507391723854;4740.881477963061;4762.190427627312;4669.34629151919;4688.085823264465;4690.179218636725;4792.520747925179;4816.133096042711;4704.388429504177;4720.881977950545;4730.455997560256;4736.905261894743;4770.880727981794;5027.271045698353;5231.215317702337;5285.603579731535;5286.36563612369;5341.839744807662;5638.323401191883;5679.346875109332;5680.034394153004;5728.541716662657;5736.024875771087;5722.513586345166;5734.139879018181;5749;5757.942420575782;5768.653880767168;5075.543201111917;5198.532132108128;5210;5295.338082739658;5340.572754179656;5790.044642633919;5797.449242321973;5820.592558520948;5848.3274423441;5851.239338886003;5555.444455554493;5559.249501317351;5611.579131565068;5612.130119831432;5617;5437.238786569884;5528.778848846001;5583;5620.606557541015;5674.744636491368;5577.860553486114;5599.048161812501;5603.271574695345;5646.2377579027125;5655.1799989001365;5566.916496252559;5601.663900166003;5607.719614019283;5617.494425501724;5625.606207565431;4231.830726770956;4233.153369326147;4324.762138082436;4334.609885110292;4375.037491751809;3895.6883449323986;3909.0422846402244;3963.1677347757295;3989.4813674222746;3993.600639935983;5385.246065550826;5387.084195686664;5400.62195646301;5444.373897001817;5452;5472.589555783332;5719.399463056402;5723.685197314105;5725.942740572625;5728.1694154348215;5266;5311.415744268127;5342.5993050521365;5366.356037275474;5414.269073675082;4882.95117048825;4894.730789806595;4902.117618828645;4907.779149938261;4928.236123400879;5623.775048997994;5830.737616807254;5861.472467477947;5881.794137205177;5904.498117660047;5584.357798853021;5604.8038318658655;5608.747606357805;5637.379888212211;5700.315962084576;5813.593048486566;5815.912761308668;5819.679917604572;5837.182794408871;5837.328707198726;5300.098983172793;5503.284573005483;5541.722913854372;5564.944350556442;5567.777288908398;5552.389237183826;5617.269754931833;5638.23883775685;5680.9147862781265;5721.22763426932;5448.618596698351;5470.234167216513;5491.917621235605;5509.834704958857;5510.504054635102;5331.893362132813;5486.61593688438;5488.862778430571;5501.624593851537;5536.667799932017;5368.060589396762;5378.97310513445;5406.378266499403;5440.673559586279;5495.890082198253;5550.47270509301;5553.6390134641115;5594.888102238014;5599.916001260066;5602.13166959122;5332.733363331896;5402.702851343214;5409.350877894678;5411.323584551931;5411.756470958738 19:44:52 INFO - PROCESS | 4851 | |1;ext.html;587.697335872026;600.4835841176588;616.3466725271213;620.8478922663948;642.1459458919637;43907.78046109769;44833.77583112084;44951.55048449514;45112.32331515026;48330.758346208306;45378.773106134424;45965.54034459654;46051.76974115133;46056.309155362695;46143;9067.637294508211;9195;9197.080291970817;9201.86197207039;9205.493697846616;9088;9104.772380690469;9184.724458266262;9191.494467804268;9204.125608067223;9188.448693078402;9227.723168304961;9230.307726920475;9233;9245.44527328359;9173.174414302712;9207.355485116044;9207.58565864531;9208.401453905508;9220.815583688322;9017.81964360709;9117.407368521091;9119.133682300171;9127.771805704844;9137.725868223964;43195.568044319785;43640.563594364285;43677.12645747067;43700;43720.12559748787;3743.47591337213;3819.3698039823394;3895.8636447724193;3929.607039296076;4259.850905218303;33429.6657033429;33546.832265838515;34327.656723432694;34407.827960860035;34491.31017379638;4386.758728269944;4393.384926110347;4422.425084738996;4448.777561121931;4538.069695712404;343284;344073;347679;348138;348291;58532.41467585355;58548.707256464295;58974;59050.704746476855;59071;38920.80539597284;55888.16167757487;56374.436255636916;56464.15303770447;56471.435285647436;17237.224324905408;17533.473995780147;17614.647707046042;17614.91192544029;17664.116794160498;13478.932605336911;13562.186268823902;13572.1856688599;13584.524541641193;13585.184888906697;19598.70601940972;19817.108230129677;19918.203271868962;19940.205381515443;19975;7248.383887369527;7278.98094266803;7279.199288078307;7291.07631121054;7437.85124297511;971.9562619682131;1016.4612755239734;1062.5802807890839;1071.973200669974;1125.8198688210011;32864.1783955403;32995.340093198;33348.83325583405;33741.83129084339;34421.311573768886;1154.6766905266538;1188.2929656854158;1205.1323047405854;1216.5004577961515;1274.388293619068;17487.03821289816;17487.30050797979;17521.7371739424;17633.559161021076;17642.647147057243;615275;618847;620842;621879;626317;59798.10302845461;60537.394626053174;60751.392486075456;60900.390996089474;60910.39089609136;1215.3194211241732;1336.8061631063636;1366.3919555797575;1385.4596707284168;1385.591250581081;32260;34444.65555344414;34599.13502162466;34715;35019.124521887155;51599.74200128976;52074;52094.21858672199;52244.47755522396;52271.47728522666;407734;408914;409863;410285;411299;22502.437439064153;28761.712382875903;28864.567031494124;29245.85377073186;29840.10479685613;47646.761766190946;47852;48634;48680;48820.75589622029;40444.1911161781;41473.1705365897;41596.58403415927;42117.36823947582;43542;40736.79631601823;40895.591044089175;41070.58929410787;42011.579884200764;42929.78535107304;41558.79220603878;41970.160596788504;42538;42556.148877022904;42714;33686.663133368354;37026.25947480981;37144.25711485701;37811;37814;28413.289667758472;28436.857815710788;29011;29055.7094429053;30507 19:44:52 INFO - PROCESS | 4851 | |2;jquery.html;396.17397725741824;401.78906074311;405.5984575270482;407.9694022948279;422.33060598950664;13744.518941837037;18586.721199182008;20157.496062598424;20163.193472261093;21417.57164856702;15678.686426271497;15808.920955395208;17497.56256093598;19986.000699965018;19994.10026548805;5658.405867383917;5753;5848.41515848416;5864.0031194697;6059.272887253535;5942.821715348525;5953.702314884238;5965.164876917213;5969.641821490703;5982.0727787193055;5945.524358051367;5976.5517586180995;5977.820665380045;5979.073243647242;5991.041433370661;5950.047992321208;5971.641701497881;5971.671558064305;5974.283086029661;5977.970110149443;5170.302009228743;5421.457854214586;5470.452954704498;5530.917036244459;5532.059549876531;14306.21315827629;14566.4901728439;19263.325783597647;23599.29202123939;23805.880970595208;2236.1956770869547;2283.349245465051;2327.8486898351553;2333.300009996994;2450.387403149213;9803.90196098037;10304.330218535772;10655.254132210748;10705.411202383866;10987.780244395068;1945.737325461073;1967.645823751724;1969.0716030541996;1970.6551353513078;1981.5399226378288;75359.24640753577;80101.59949200274;81103;83083;83625;9423.481708506099;10062.496875156214;10122.696319110439;10200.846987295197;10271.486425678686;13767.931160344135;13821.654458638413;13844.030917835655;13896.77651575743;13911.23488208138;7985.920140798634;8169.305609023299;8173.754787356389;8234.176582341717;8370.916290837136;8200.774868593167;8244.917550824535;8303.377246706521;8465.365097617703;8471.407001509959;4192.350185721218;4267.16790225903;4300.118475712471;4337.696361254745;4364.454443194601;1974.151115020555;1996.976093172259;2050.1286953044896;2061.29915828616;2131.776163502841;902.9413088149249;925.9120383563551;997.1275134257525;1006.9043440873105;1100.5762781329086;19210.231590736214;20101.798982010285;20185.49536261576;20386.79613203849;20431.387058387976;949.3212353167519;950.6720181537358;962.9470379129215;989.366805244644;992.7883297532477;10900.34597924127;11323.112606285344;11367.147463940237;11410.258333208314;11418.371989540656;463896;470406;471624.776885041;475459;477160;89320.5533972326;90603.54698226467;91361.54319228361;91560;92477.53761231151;935.9485228312508;965.7006328038331;983.4197823284154;992.3252188511736;995.3729150635054;17490.387836425663;18077.548061298046;18280.360187393377;18409.263629455207;18441.446756597325;40290.597094028686;41496.17007659769;42951.57048429476;43288;43858.78070609754;58534.12198816936;58963.70518147553;58967.70516147392;59212;60945;8742.694005710024;8980.86528702083;9005.504697241715;9059.954700226457;9360.4383736976;7761.417893658111;11549.769004620028;11718.941405292919;13583.185008899498;13798.17210967345;11630.244034137753;11848.585299514476;12009.39953002329;12111.152219344562;12269.202501837352;11787.174897757075;11919.76160476803;11973.820392694292;12265.448054837732;12381.876181238073;12227.510899563918;12264.44809983535;12283.69290767738;12310.373509723871;12506.37468126572;7724.806879828049;8199.180081991753;8850.822983540422;8874.955625221832;9122.27021838238;11491.94254028758;11878.762424751629;11919.52321907114;11998.280103193836;12281.570145044881 19:44:52 INFO - PROCESS | 4851 | |3;mootools.html;1340.0636080863144;1459.8832093432522;1510.0109428324447;1628.1370873437074;1755.587436952315;7354.301341372568;8638.740837774869;8905.643774249022;8905.688300909469;9173.541322933843;8945.418547794388;8954.820903581924;8971.685990990316;8972.685955991541;8973;2363.7636236376397;2382.499675068229;2385.3082606044313;2388.22382725614;2391.0316321889577;2363.6690863279155;2395.101836811196;2441.8168637352182;2444.6577479152934;2444.9755502445014;2411.131992482705;2412.6742889709926;2422.4065104049446;2436.7328988925838;2444.5966415541416;2326.4532834783813;2338.7193536775612;2346.3078391874437;2347.765223477647;2373.8219633527556;2301.6432452969816;2328.2084091408833;2328.860268383902;2333.5216280662426;2339.017612602711;8901.243394311427;8929.776755581097;8936.910630893673;9067.863982040275;9076.8638470423;5401.3248343957;5590.440955904418;5636.661800291996;5638.210650508932;5638.407967163471;3477.3045390921916;3487.267673788482;3522.8590856365713;3549.396602577568;3574.517440145573;1113.4098927568402;1127.3067063755732;1143.1026644084354;1152.659965310227;1171.824226366052;12454.190477618926;12518.874811251862;12542.059345549029;12554.74890502194;12613.495460181583;4543.25036369002;4758.143534163814;4853.805847766121;4855.514448555187;4876.317315575822;9952.950235248778;10004.099631033241;10016.699499015042;10017.69946901594;10025.398476091457;5508;5517.36550296713;5527.2538207342295;5533.501984821385;5555.944440555542;4357.607815296638;4494.168578812864;4666.209941386958;5064.18972964324;5162.225666150032;3793.4878791363367;3898.2398432305426;3933.527976642821;4013.3979903014197;4025.295573274665;1130.8473356096986;1152.7348709796793;1164.394514852272;1190.101473387604;1196.5931583261734;882.6028287270625;1004.5178314409125;1005.5525291245472;1010.5452546354023;1014.2089170447063;6091.2081429414775;6186.659733714599;6278.623282603058;6421.229452465734;6478.676066196671;993.6671215142864;1019.938803671782;1026.6243234066387;1031.0617338222182;1066.5360568152878;4194.958050419456;4228.534861165269;4308.89227769302;4408.096340250241;4494.348319493655;11302.773944521063;11507.769844603228;11593.072554195644;11596.420178991017;11600.071994240441;11145.331280123057;11507.07943364529;11511.19421640477;11520;11524.135689823297;7967.402444816662;8148.266656000988;8157.95921020403;8162.510249384937;8167.305779008732;5773.278340207474;5834.824955251349;5934.19888315072;5936.317323507852;6029.60807547508;9715.562799674173;9734.41593504392;9767.365121267096;9844.064813842673;9963.50182490887;8913.197812196933;9953.601855925683;9975.45135017581;10479.580816767244;10501;7626.9618651906385;7722.806929826799;7818.140004599376;7875;7892.763217103496;8585.399022068395;8660.350473714623;8781.824363512565;8783.341249406192;8785.165409286108;7656.961715191388;7683.577403242652;7755.72854950074;7784.84430311402;8019.719309824128;7518.135414427411;7714;7784.104827944859;7803.570803605629;8057.879131812911;6938.791836244921;7058.11773528309;7074.787756367317;7086.255943125898;7093.716251349888;5208.765605547684;5228.032813929283;5380.946190538045;5513.669179849061;5526.889462210814;5167.509086636764;5237.738113094406;5283;5391.649542779707;5725.112607545837 19:44:52 INFO - PROCESS | 4851 | |4;prototype.html;329.61561441943826;341.5082281514619;352.83945804658873;352.86238367036844;352.88354842901845;5197.194434862593;5253.212018197272;5369.4362091980365;5402.189671549265;5417.322834645669;5179.119549676555;5215.1655735082395;5217.47825217478;5233.921491177635;5249.842504724865;4123.752574845504;4138.441310423092;4141.751494910315;4148.108156746299;4166.208420400114;3934.645881870631;4069.3489041753323;4146.5853414658595;4156.729812562189;4164.75011499311;4066.207089617526;4069.674426045924;4070.2266569351877;4072.8370865165357;4078.8368465261356;4046.6358027777496;4049.4330793688905;4055.1281474482835;4077.938830917539;4084.7140700151003;3510.8946731598094;3603.2433189030335;3621.185233322508;3629.818509074562;3651.3427583035045;5142.125838607446;5149.459306772772;5156.535911767922;5171.95707695462;5189.948100518985;4700.130475861943;4736.123817093849;4755.619550435991;4756.453007904066;4757.809687612491;5336.3724064160415;5394.32570928634;5465.72671366434;5483.340579818785;5500.614956953015;3974.5826688197612;3977.962368196283;3985.720999530034;3985.860494882665;3990.9600903990877;5695.231143795576;5722.97138514309;5728.627639203439;5729.226554415184;5732.168835518868;4043.272211001989;4077.2660921033907;4096.897577560525;4103.835846566164;4117.444145040441;4126;4127.482235836138;4127.834886604563;4134.152498737752;4137.862087925844;4676.3453116563705;4695.929561056587;4700.459447163552;4713.552212539872;4714.363560919301;5356.5179133877355;5490.80782172622;5504.972475137599;5517.36550296721;5584.022796010682;1352.1413902172192;1388.94444222232;1389.6456403617215;1391.1166409330149;1396.8114304568956;972.7470857577081;990.9207263418909;995.4723996281982;1002.4787110702393;1008.7326858382535;5321.148616221463;5353.518183363516;5355.625106242525;5385.676859388449;5405.5135037846785;5527.407185329568;5527.50252477279;5576.888462230732;5607.130894711245;5640.379558248589;5220.791168353223;5248.291480650063;5254.922740838119;5285.814996475105;5290.28581141541;2608.608708693673;2622.645942797736;2625.448655782269;2629.7238789926782;2633.3548280671357;4805.903881922342;5041.7227052511735;5111.881752131197;5117.590592752571;5147.819826306061;5008.549230569266;5014.495651304594;5038.874028149252;5063.455646027923;5071.87320316995;4864.24887040658;5100.974495127501;5104.719240441811;5113;5160.767765450564;4905.7792399342825;4915.877103072452;4917.877053073702;4919.114559379346;5000;4900.583450406755;4907.435644900882;4913.361263035926;4925.408950925912;4930.580900623344;5057.898842023065;5058.797648094035;5092.060701119908;5114.514121158484;5420.783168673209;4042.3128068227884;4079.4905884822806;4080.510338759368;4119.867036564969;4126.401671757519;5653.406392328746;5685.9530568994305;5717.971410142923;5731.19763233156;5758.395368486416;5191.195364718475;5479.917801233064;5595.356533998643;5609.859753506195;5615.719214039364;5221.765020574159;5484.462521673578;5502.642328248651;5508.504234618743;5511.972440137934;5302.946970530245;5445.237666726583;5455.618106732491;5459.481349271813;5484.533814625802;5580.888382232251;5581.957832981117;5583.218349431002;5602.03928624978;5603;5174.456682048331;5418.884141618664;5425.647332923348;5426.399212232322;5438.428964958623 19:44:52 INFO - PROCESS | 4851 | |5;yui.html;798.14997028165;814.9551774652394;846.6020970144037;847.3856454070799;859.8581234096371;15611.765823512642;16944;17287.222075006623;17298.13509324535;18177.545561360974;17101.486955391327;17369.913150434262;17470;17479.82520174798;17490.912545437255;812.9878051829213;832.2218725491655;834.5827086456771;835.2315869400167;835.3526017336552;673.1551902362528;793.3851265269404;816.420341557492;818.5129847740615;833.2708879730236;871.716692075075;877.188600544497;878.0122362342365;880.3705350674267;880.9559522023873;860.5525126933959;867.9132086791334;868.1882439918696;868.5743985447149;868.6134670071821;505.24465923444683;606.372404561277;623.5416968528128;633.882731694638;637.7449020391834;15963.840361596467;16005.679886402324;16028.118453485054;16031.35874565016;16049.438269660623;14280.571582852532;14730.852691473056;14772.70454590913;14903.925480372638;15162.09027458345;14044.438222471088;14066.367013484418;14125.434982600684;14243.928780356135;14912.776308355384;4577.450705915278;4590.9540904591195;4602.355670206174;4707.176244157293;4833.275008748681;66202.3379766201;66725.66637166831;66978.33021669819;75205.62397188034;87780.5610971941;11261.772494673847;11296.887031129583;11319.830202546967;11449.313041217554;11561.710957226136;21774.673379899315;21844.12623495074;21848.563028739336;21984;22018.229361972255;8049.6377663005305;8134.511929284144;8157.388195885333;8395.28640065589;8471.49171049739;4501.347304640809;4566.178087944202;4584.358189853423;4719.83480578185;4772.665913386099;3532.6467353264984;3639.8180090995343;3648.416253399443;3651.507046548735;3663.908402289964;651.9674016299166;654.3718030690591;661.0612929639923;667.3793372163934;698.0924797762888;753.3596443023496;758.3402439877342;781.9648115834801;803.9075506316732;814.5479259011306;10148.340357876861;10883.499307196207;10912.672619821418;11051.944740276407;11182.16133789969;739.9112106547249;786.194150995223;793.8610743119931;796.1839114907266;802.0495712580611;4758.809647614126;5216.06110900034;5310.230016647642;5338.759755810997;5344.67931924078;125272.74727252794;126932.36533817457;130111;133141.3342933279;135731.32134339464;102087.48956255171;107439.46280268549;110591;112119.4394028041;114547;58614;58648;96821;96833.51583242038;100200.49899750455;9561.808763824823;9872.506374681381;10028.690532124236;10085.747856303358;10207.33652312627;55738.72130639321;56041.86394218789;56077.158842616576;57211;57769;76287.56112865162;77571.61214193892;78033.63596368472;79866;81055.59472202601;12385.318807465675;12932.612021639367;13083.869161308265;13187.274699891597;13610.115342502706;14266.559399302943;14490.565283041526;14618.41526338977;14686.192259425834;14703.779443308573;13616.38726257301;14076.507322243673;14140.15159090458;14234.572962811133;14680.26598670041;13624.93187534056;14031.158130512202;14222.217778022308;14438.205898675673;14656;13876.23680697531;13894.305284735925;13920.234387108401;14124.2937853109;14395.568132956028;10775.730606734895;11177;11187.272827266203;11321.150913681342;11394.37330946806;9271.953640231755;10554.2084343673;11263.875249233739;11556.364399958084;11744 19:44:52 INFO - PROCESS | 4851 | __end_tp_report 19:44:52 INFO - PROCESS | 4851 | __start_cc_report 19:44:52 INFO - PROCESS | 4851 | _x_x_mozilla_cycle_collect,977 19:44:52 INFO - PROCESS | 4851 | __end_cc_report 19:44:52 INFO - PROCESS | 4851 | __startTimestamp1470624292106__endTimestamp 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | ------- Summary: start ------- 19:44:52 INFO - PROCESS | 4851 | Number of tests: 6 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#0] dojo.html Cycles:165 Average:5237.01 Median:5414.27 stddev:595.34 (11.0%) stddev-sans-first:571.16 19:44:52 INFO - PROCESS | 4851 | Values: 3018.3 3022.3 3059.1 3065.3 3070.9 5082.0 5156.7 5276.3 5325.1 5335.9 5261.3 5270.3 5310.4 5336.8 5354.9 4686.4 4721.9 4765.7 4768.2 4899.0 4824.5 4852.1 4853.8 4866.3 4889.7 4642.9 4690.8 4691.5 4740.9 4762.2 4669.3 4688.1 4690.2 4792.5 4816.1 4704.4 4720.9 4730.5 4736.9 4770.9 5027.3 5231.2 5285.6 5286.4 5341.8 5638.3 5679.3 5680.0 5728.5 5736.0 5722.5 5734.1 5749.0 5757.9 5768.7 5075.5 5198.5 5210.0 5295.3 5340.6 5790.0 5797.4 5820.6 5848.3 5851.2 5555.4 5559.2 5611.6 5612.1 5617.0 5437.2 5528.8 5583.0 5620.6 5674.7 5577.9 5599.0 5603.3 5646.2 5655.2 5566.9 5601.7 5607.7 5617.5 5625.6 4231.8 4233.2 4324.8 4334.6 4375.0 3895.7 3909.0 3963.2 3989.5 3993.6 5385.2 5387.1 5400.6 5444.4 5452.0 5472.6 5719.4 5723.7 5725.9 5728.2 5266.0 5311.4 5342.6 5366.4 5414.3 4883.0 4894.7 4902.1 4907.8 4928.2 5623.8 5830.7 5861.5 5881.8 5904.5 5584.4 5604.8 5608.7 5637.4 5700.3 5813.6 5815.9 5819.7 5837.2 5837.3 5300.1 5503.3 5541.7 5564.9 5567.8 5552.4 5617.3 5638.2 5680.9 5721.2 5448.6 5470.2 5491.9 5509.8 5510.5 5331.9 5486.6 5488.9 5501.6 5536.7 5368.1 5379.0 5406.4 5440.7 5495.9 5550.5 5553.6 5594.9 5599.9 5602.1 5332.7 5402.7 5409.4 5411.3 5411.8 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#1] ext.html Cycles:180 Average:62203.18 Median:23408.00 stddev:125971.92 (538.2%) stddev-sans-first:126240.36 19:44:52 INFO - PROCESS | 4851 | Values: 587.7 600.5 616.3 620.8 642.1 43907.8 44833.8 44951.6 45112.3 48330.8 45378.8 45965.5 46051.8 46056.3 46143.0 9067.6 9195.0 9197.1 9201.9 9205.5 9088.0 9104.8 9184.7 9191.5 9204.1 9188.4 9227.7 9230.3 9233.0 9245.4 9173.2 9207.4 9207.6 9208.4 9220.8 9017.8 9117.4 9119.1 9127.8 9137.7 43195.6 43640.6 43677.1 43700.0 43720.1 3743.5 3819.4 3895.9 3929.6 4259.9 33429.7 33546.8 34327.7 34407.8 34491.3 4386.8 4393.4 4422.4 4448.8 4538.1 343284.0 344073.0 347679.0 348138.0 348291.0 58532.4 58548.7 58974.0 59050.7 59071.0 38920.8 55888.2 56374.4 56464.2 56471.4 17237.2 17533.5 17614.6 17614.9 17664.1 13478.9 13562.2 13572.2 13584.5 13585.2 19598.7 19817.1 19918.2 19940.2 19975.0 7248.4 7279.0 7279.2 7291.1 7437.9 972.0 1016.5 1062.6 1072.0 1125.8 32864.2 32995.3 33348.8 33741.8 34421.3 1154.7 1188.3 1205.1 1216.5 1274.4 17487.0 17487.3 17521.7 17633.6 17642.6 615275.0 618847.0 620842.0 621879.0 626317.0 59798.1 60537.4 60751.4 60900.4 60910.4 1215.3 1336.8 1366.4 1385.5 1385.6 32260.0 34444.7 34599.1 34715.0 35019.1 51599.7 52074.0 52094.2 52244.5 52271.5 407734.0 408914.0 409863.0 410285.0 411299.0 22502.4 28761.7 28864.6 29245.9 29840.1 47646.8 47852.0 48634.0 48680.0 48820.8 40444.2 41473.2 41596.6 42117.4 43542.0 40736.8 40895.6 41070.6 42011.6 42929.8 41558.8 41970.2 42538.0 42556.1 42714.0 33686.7 37026.3 37144.3 37811.0 37814.0 28413.3 28436.9 29011.0 29055.7 30507.0 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#2] jquery.html Cycles:180 Average:28385.63 Median:2307.34 stddev:77890.42 (3375.8%) stddev-sans-first:78080.57 19:44:52 INFO - PROCESS | 4851 | Values: 396.2 401.8 405.6 408.0 422.3 13744.5 18586.7 20157.5 20163.2 21417.6 15678.7 15808.9 17497.6 19986.0 19994.1 5658.4 5753.0 5848.4 5864.0 6059.3 5942.8 5953.7 5965.2 5969.6 5982.1 5945.5 5976.6 5977.8 5979.1 5991.0 5950.0 5971.6 5971.7 5974.3 5978.0 5170.3 5421.5 5470.5 5530.9 5532.1 14306.2 14566.5 19263.3 23599.3 23805.9 2236.2 2283.3 2327.8 2333.3 2450.4 9803.9 10304.3 10655.3 10705.4 10987.8 1945.7 1967.6 1969.1 1970.7 1981.5 75359.2 80101.6 81103.0 83083.0 83625.0 9423.5 10062.5 10122.7 10200.8 10271.5 13767.9 13821.7 13844.0 13896.8 13911.2 7985.9 8169.3 8173.8 8234.2 8370.9 8200.8 8244.9 8303.4 8465.4 8471.4 4192.4 4267.2 4300.1 4337.7 4364.5 1974.2 1997.0 2050.1 2061.3 2131.8 902.9 925.9 997.1 1006.9 1100.6 19210.2 20101.8 20185.5 20386.8 20431.4 949.3 950.7 962.9 989.4 992.8 10900.3 11323.1 11367.1 11410.3 11418.4 463896.0 470406.0 471624.8 475459.0 477160.0 89320.6 90603.5 91361.5 91560.0 92477.5 935.9 965.7 983.4 992.3 995.4 17490.4 18077.5 18280.4 18409.3 18441.4 40290.6 41496.2 42951.6 43288.0 43858.8 58534.1 58963.7 58967.7 59212.0 60945.0 8742.7 8980.9 9005.5 9060.0 9360.4 7761.4 11549.8 11718.9 13583.2 13798.2 11630.2 11848.6 12009.4 12111.2 12269.2 11787.2 11919.8 11973.8 12265.4 12381.9 12227.5 12264.4 12283.7 12310.4 12506.4 7724.8 8199.2 8850.8 8875.0 9122.3 11491.9 11878.8 11919.5 11998.3 12281.6 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#3] mootools.html Cycles:180 Average:5892.95 Median:4865.92 stddev:3328.11 (68.4%) stddev-sans-first:3319.86 19:44:52 INFO - PROCESS | 4851 | Values: 1340.1 1459.9 1510.0 1628.1 1755.6 7354.3 8638.7 8905.6 8905.7 9173.5 8945.4 8954.8 8971.7 8972.7 8973.0 2363.8 2382.5 2385.3 2388.2 2391.0 2363.7 2395.1 2441.8 2444.7 2445.0 2411.1 2412.7 2422.4 2436.7 2444.6 2326.5 2338.7 2346.3 2347.8 2373.8 2301.6 2328.2 2328.9 2333.5 2339.0 8901.2 8929.8 8936.9 9067.9 9076.9 5401.3 5590.4 5636.7 5638.2 5638.4 3477.3 3487.3 3522.9 3549.4 3574.5 1113.4 1127.3 1143.1 1152.7 1171.8 12454.2 12518.9 12542.1 12554.7 12613.5 4543.3 4758.1 4853.8 4855.5 4876.3 9953.0 10004.1 10016.7 10017.7 10025.4 5508.0 5517.4 5527.3 5533.5 5555.9 4357.6 4494.2 4666.2 5064.2 5162.2 3793.5 3898.2 3933.5 4013.4 4025.3 1130.8 1152.7 1164.4 1190.1 1196.6 882.6 1004.5 1005.6 1010.5 1014.2 6091.2 6186.7 6278.6 6421.2 6478.7 993.7 1019.9 1026.6 1031.1 1066.5 4195.0 4228.5 4308.9 4408.1 4494.3 11302.8 11507.8 11593.1 11596.4 11600.1 11145.3 11507.1 11511.2 11520.0 11524.1 7967.4 8148.3 8158.0 8162.5 8167.3 5773.3 5834.8 5934.2 5936.3 6029.6 9715.6 9734.4 9767.4 9844.1 9963.5 8913.2 9953.6 9975.5 10479.6 10501.0 7627.0 7722.8 7818.1 7875.0 7892.8 8585.4 8660.4 8781.8 8783.3 8785.2 7657.0 7683.6 7755.7 7784.8 8019.7 7518.1 7714.0 7784.1 7803.6 8057.9 6938.8 7058.1 7074.8 7086.3 7093.7 5208.8 5228.0 5380.9 5513.7 5526.9 5167.5 5237.7 5283.0 5391.6 5725.1 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#4] prototype.html Cycles:180 Average:4522.58 Median:5067.66 stddev:1300.48 (25.7%) stddev-sans-first:1265.48 19:44:52 INFO - PROCESS | 4851 | Values: 329.6 341.5 352.8 352.9 352.9 5197.2 5253.2 5369.4 5402.2 5417.3 5179.1 5215.2 5217.5 5233.9 5249.8 4123.8 4138.4 4141.8 4148.1 4166.2 3934.6 4069.3 4146.6 4156.7 4164.8 4066.2 4069.7 4070.2 4072.8 4078.8 4046.6 4049.4 4055.1 4077.9 4084.7 3510.9 3603.2 3621.2 3629.8 3651.3 5142.1 5149.5 5156.5 5172.0 5189.9 4700.1 4736.1 4755.6 4756.5 4757.8 5336.4 5394.3 5465.7 5483.3 5500.6 3974.6 3978.0 3985.7 3985.9 3991.0 5695.2 5723.0 5728.6 5729.2 5732.2 4043.3 4077.3 4096.9 4103.8 4117.4 4126.0 4127.5 4127.8 4134.2 4137.9 4676.3 4695.9 4700.5 4713.6 4714.4 5356.5 5490.8 5505.0 5517.4 5584.0 1352.1 1388.9 1389.6 1391.1 1396.8 972.7 990.9 995.5 1002.5 1008.7 5321.1 5353.5 5355.6 5385.7 5405.5 5527.4 5527.5 5576.9 5607.1 5640.4 5220.8 5248.3 5254.9 5285.8 5290.3 2608.6 2622.6 2625.4 2629.7 2633.4 4805.9 5041.7 5111.9 5117.6 5147.8 5008.5 5014.5 5038.9 5063.5 5071.9 4864.2 5101.0 5104.7 5113.0 5160.8 4905.8 4915.9 4917.9 4919.1 5000.0 4900.6 4907.4 4913.4 4925.4 4930.6 5057.9 5058.8 5092.1 5114.5 5420.8 4042.3 4079.5 4080.5 4119.9 4126.4 5653.4 5686.0 5718.0 5731.2 5758.4 5191.2 5479.9 5595.4 5609.9 5615.7 5221.8 5484.5 5502.6 5508.5 5512.0 5302.9 5445.2 5455.6 5459.5 5484.5 5580.9 5582.0 5583.2 5602.0 5603.0 5174.5 5418.9 5425.6 5426.4 5438.4 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - PROCESS | 4851 | [#5] yui.html Cycles:180 Average:21902.98 Median:3649.96 stddev:32152.31 (880.9%) stddev-sans-first:32203.45 19:44:52 INFO - PROCESS | 4851 | Values: 798.1 815.0 846.6 847.4 859.9 15611.8 16944.0 17287.2 17298.1 18177.5 17101.5 17369.9 17470.0 17479.8 17490.9 813.0 832.2 834.6 835.2 835.4 673.2 793.4 816.4 818.5 833.3 871.7 877.2 878.0 880.4 881.0 860.6 867.9 868.2 868.6 868.6 505.2 606.4 623.5 633.9 637.7 15963.8 16005.7 16028.1 16031.4 16049.4 14280.6 14730.9 14772.7 14903.9 15162.1 14044.4 14066.4 14125.4 14243.9 14912.8 4577.5 4591.0 4602.4 4707.2 4833.3 66202.3 66725.7 66978.3 75205.6 87780.6 11261.8 11296.9 11319.8 11449.3 11561.7 21774.7 21844.1 21848.6 21984.0 22018.2 8049.6 8134.5 8157.4 8395.3 8471.5 4501.3 4566.2 4584.4 4719.8 4772.7 3532.6 3639.8 3648.4 3651.5 3663.9 652.0 654.4 661.1 667.4 698.1 753.4 758.3 782.0 803.9 814.5 10148.3 10883.5 10912.7 11051.9 11182.2 739.9 786.2 793.9 796.2 802.0 4758.8 5216.1 5310.2 5338.8 5344.7 125272.7 126932.4 130111.0 133141.3 135731.3 102087.5 107439.5 110591.0 112119.4 114547.0 58614.0 58648.0 96821.0 96833.5 100200.5 9561.8 9872.5 10028.7 10085.7 10207.3 55738.7 56041.9 56077.2 57211.0 57769.0 76287.6 77571.6 78033.6 79866.0 81055.6 12385.3 12932.6 13083.9 13187.3 13610.1 14266.6 14490.6 14618.4 14686.2 14703.8 13616.4 14076.5 14140.2 14234.6 14680.3 13624.9 14031.2 14222.2 14438.2 14656.0 13876.2 13894.3 13920.2 14124.3 14395.6 10775.7 11177.0 11187.3 11321.2 11394.4 9272.0 10554.2 11263.9 11556.4 11744.0 19:44:52 INFO - PROCESS | 4851 | -------- Summary: end -------- 19:44:52 INFO - PROCESS | 4851 | 19:44:52 INFO - TEST-INFO | 4851: exit 0 19:44:52 INFO - TEST-OK | dromaeo_css | took 1114274ms 19:44:52 INFO - TEST-START | kraken 19:44:52 INFO - operating with platform_type : linux_ 19:44:52 INFO - Initialising browser for kraken test... 19:44:52 INFO - TEST-INFO | started process 5042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUzxvXD/profile http://localhost:36493/getInfo.html) 19:44:56 INFO - PROCESS | 5042 | __metrics Screen width/height:1600/1200 19:44:56 INFO - PROCESS | 5042 | colorDepth:24 19:44:56 INFO - PROCESS | 5042 | Browser inner width/height: 1024/697 19:44:56 INFO - PROCESS | 5042 | __metrics 19:44:57 INFO - PROCESS | 5042 | 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" 19:44:57 INFO - PROCESS | 5042 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:44:58 INFO - TEST-INFO | 5042: exit 0 19:44:58 INFO - Browser initialized. 19:44:58 INFO - Running cycle 1/1 for kraken test... 19:44:58 INFO - TEST-INFO | started process 5123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUzxvXD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:45:10 INFO - PROCESS | 5123 | RSS: Main: 156700672 19:45:10 INFO - PROCESS | 5123 | 19:45:12 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36493/tests/kraken/driver.html?testName=audio-beat-detection) 19:45:13 INFO - PROCESS | 5123 | RSS: Main: 212692992 19:45:13 INFO - PROCESS | 5123 | 19:45:16 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36493/tests/kraken/driver.html?testName=audio-dft) 19:45:16 INFO - PROCESS | 5123 | RSS: Main: 192892928 19:45:16 INFO - PROCESS | 5123 | 19:45:19 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36493/tests/kraken/driver.html?testName=audio-fft) 19:45:20 INFO - PROCESS | 5123 | RSS: Main: 270450688 19:45:20 INFO - PROCESS | 5123 | 19:45:22 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36493/tests/kraken/driver.html?testName=audio-oscillator) 19:45:23 INFO - PROCESS | 5123 | RSS: Main: 178720768 19:45:23 INFO - PROCESS | 5123 | 19:45:25 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:45:25 INFO - PROCESS | 5123 | RSS: Main: 184537088 19:45:25 INFO - PROCESS | 5123 | 19:45:28 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36493/tests/kraken/driver.html?testName=imaging-darkroom) 19:45:29 INFO - PROCESS | 5123 | RSS: Main: 219189248 19:45:29 INFO - PROCESS | 5123 | 19:45:32 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36493/tests/kraken/driver.html?testName=imaging-desaturate) 19:45:33 INFO - PROCESS | 5123 | RSS: Main: 219897856 19:45:33 INFO - PROCESS | 5123 | 19:45:36 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36493/tests/kraken/driver.html?testName=json-parse-financial) 19:45:36 INFO - PROCESS | 5123 | RSS: Main: 219840512 19:45:36 INFO - PROCESS | 5123 | 19:45:38 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:45:39 INFO - PROCESS | 5123 | RSS: Main: 183730176 19:45:39 INFO - PROCESS | 5123 | 19:45:40 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:45:41 INFO - PROCESS | 5123 | RSS: Main: 189693952 19:45:41 INFO - PROCESS | 5123 | 19:45:43 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:45:44 INFO - PROCESS | 5123 | RSS: Main: 194445312 19:45:44 INFO - PROCESS | 5123 | 19:45:46 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:45:47 INFO - PROCESS | 5123 | RSS: Main: 189579264 19:45:47 INFO - PROCESS | 5123 | 19:45:49 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:45:50 INFO - PROCESS | 5123 | RSS: Main: 191766528 19:45:50 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:36493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36493/tests/kraken/driver.html?testName=ai-astar) 19:45:52 INFO - PROCESS | 5123 | RSS: Main: 243859456 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | __start_tp_report 19:45:52 INFO - PROCESS | 5123 | _x_x_mozilla_page_load 19:45:52 INFO - PROCESS | 5123 | _x_x_mozilla_page_load_details 19:45:52 INFO - PROCESS | 5123 | |i|pagename|runs| 19:45:52 INFO - PROCESS | 5123 | |0;ai-astar;102;111;110;110;111;111;111;109;114;110 19:45:52 INFO - PROCESS | 5123 | |1;audio-beat-detection;143;119;117;173;123;119;154;123;156;121 19:45:52 INFO - PROCESS | 5123 | |2;audio-dft;160;159;159;158;160;159;160;161;160;161 19:45:52 INFO - PROCESS | 5123 | |3;audio-fft;75;76;75;120;75;75;75;74;99;76 19:45:52 INFO - PROCESS | 5123 | |4;audio-oscillator;84;84;133;84;84;84;121;83;83;131 19:45:52 INFO - PROCESS | 5123 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 19:45:52 INFO - PROCESS | 5123 | |6;imaging-darkroom;146;146;148;147;146;146;147;146;147;147 19:45:52 INFO - PROCESS | 5123 | |7;imaging-desaturate;95;95;96;95;94;94;95;95;95;95 19:45:52 INFO - PROCESS | 5123 | |8;json-parse-financial;52;49;49;49;55;51;50;49;50;49 19:45:52 INFO - PROCESS | 5123 | |9;json-stringify-tinderbox;60;63;60;61;62;61;61;61;61;61 19:45:52 INFO - PROCESS | 5123 | |10;stanford-crypto-aes;76;75;76;76;74;75;75;85;81;84 19:45:52 INFO - PROCESS | 5123 | |11;stanford-crypto-ccm;122;123;120;120;127;121;122;134;161;128 19:45:52 INFO - PROCESS | 5123 | |12;stanford-crypto-pbkdf2;161;157;159;158;156;163;159;163;160;159 19:45:52 INFO - PROCESS | 5123 | |13;stanford-crypto-sha256-iterative;64;63;64;63;62;63;63;63;62;62 19:45:52 INFO - PROCESS | 5123 | __end_tp_report 19:45:52 INFO - PROCESS | 5123 | __start_cc_report 19:45:52 INFO - PROCESS | 5123 | _x_x_mozilla_cycle_collect,1704 19:45:52 INFO - PROCESS | 5123 | __end_cc_report 19:45:52 INFO - PROCESS | 5123 | __startTimestamp1470624352375__endTimestamp 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | ------- Summary: start ------- 19:45:52 INFO - PROCESS | 5123 | Number of tests: 14 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.07 (2.8%) stddev-sans-first:1.39 19:45:52 INFO - PROCESS | 5123 | Values: 102.0 111.0 110.0 110.0 111.0 111.0 111.0 109.0 114.0 110.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:133.00 stddev:20.08 (15.1%) stddev-sans-first:21.08 19:45:52 INFO - PROCESS | 5123 | Values: 143.0 119.0 117.0 173.0 123.0 119.0 154.0 123.0 156.0 121.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 19:45:52 INFO - PROCESS | 5123 | Values: 160.0 159.0 159.0 158.0 160.0 159.0 160.0 161.0 160.0 161.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:15.33 (20.4%) stddev-sans-first:16.05 19:45:52 INFO - PROCESS | 5123 | Values: 75.0 76.0 75.0 120.0 75.0 75.0 75.0 74.0 99.0 76.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.00 stddev:21.77 (25.9%) stddev-sans-first:22.57 19:45:52 INFO - PROCESS | 5123 | Values: 84.0 84.0 133.0 84.0 84.0 84.0 121.0 83.0 83.0 131.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:45:52 INFO - PROCESS | 5123 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 19:45:52 INFO - PROCESS | 5123 | Values: 146.0 146.0 148.0 147.0 146.0 146.0 147.0 146.0 147.0 147.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:45:52 INFO - PROCESS | 5123 | Values: 95.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:1.95 (3.9%) stddev-sans-first:1.96 19:45:52 INFO - PROCESS | 5123 | Values: 52.0 49.0 49.0 49.0 55.0 51.0 50.0 49.0 50.0 49.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.88 (1.4%) stddev-sans-first:0.83 19:45:52 INFO - PROCESS | 5123 | Values: 60.0 63.0 60.0 61.0 62.0 61.0 61.0 61.0 61.0 61.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:76.00 stddev:4.06 (5.3%) stddev-sans-first:4.26 19:45:52 INFO - PROCESS | 5123 | Values: 76.0 75.0 76.0 76.0 74.0 75.0 75.0 85.0 81.0 84.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:125.00 stddev:12.47 (10.0%) stddev-sans-first:13.05 19:45:52 INFO - PROCESS | 5123 | Values: 122.0 123.0 120.0 120.0 127.0 121.0 122.0 134.0 161.0 128.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:159.50 stddev:2.32 (1.5%) stddev-sans-first:2.40 19:45:52 INFO - PROCESS | 5123 | Values: 161.0 157.0 159.0 158.0 156.0 163.0 159.0 163.0 160.0 159.0 19:45:52 INFO - PROCESS | 5123 | 19:45:52 INFO - PROCESS | 5123 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 19:45:52 INFO - PROCESS | 5123 | Values: 64.0 63.0 64.0 63.0 62.0 63.0 63.0 63.0 62.0 62.0 19:45:52 INFO - PROCESS | 5123 | -------- Summary: end -------- 19:45:52 INFO - PROCESS | 5123 | 19:45:53 INFO - TEST-INFO | 5123: exit 0 19:45:53 INFO - TEST-OK | kraken | took 60464ms 19:45:53 INFO - SUITE-END | took 1174s 19:45:53 INFO - Completed test suite (00:19:34) 19:45:53 INFO - javascript benchmark 19:45:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3018.33596608746, 3022.2595464111296, 3059.0669845697066, 3065.2643365592266, 3070.8771649134037, 5082.03441346144, 5156.690598564089, 5276.26132341472, 5325.121354976424, 5335.9199612005805, 5261.289725887004, 5270.262163297141, 5310.3893052299, 5336.8132115375965, 5354.892902141954, 4686.367340409052, 4721.9055618887605, 4765.666403351766, 4768.213244814601, 4899.044686286158, 4824.469308376076, 4852.126617208901, 4853.757312134397, 4866.294387313837, 4889.657723959324, 4642.907141857161, 4690.7654617269, 4691.507391723854, 4740.881477963061, 4762.190427627312, 4669.34629151919, 4688.085823264465, 4690.179218636725, 4792.520747925179, 4816.133096042711, 4704.388429504177, 4720.881977950545, 4730.455997560256, 4736.905261894743, 4770.880727981794, 5027.271045698353, 5231.215317702337, 5285.603579731535, 5286.36563612369, 5341.839744807662, 5638.323401191883, 5679.346875109332, 5680.034394153004, 5728.541716662657, 5736.024875771087, 5722.513586345166, 5734.139879018181, 5749.0, 5757.942420575782, 5768.653880767168, 5075.543201111917, 5198.532132108128, 5210.0, 5295.338082739658, 5340.572754179656, 5790.044642633919, 5797.449242321973, 5820.592558520948, 5848.3274423441, 5851.239338886003, 5555.444455554493, 5559.249501317351, 5611.579131565068, 5612.130119831432, 5617.0, 5437.238786569884, 5528.778848846001, 5583.0, 5620.606557541015, 5674.744636491368, 5577.860553486114, 5599.048161812501, 5603.271574695345, 5646.2377579027125, 5655.1799989001365, 5566.916496252559, 5601.663900166003, 5607.719614019283, 5617.494425501724, 5625.606207565431, 4231.830726770956, 4233.153369326147, 4324.762138082436, 4334.609885110292, 4375.037491751809, 3895.6883449323986, 3909.0422846402244, 3963.1677347757295, 3989.4813674222746, 3993.600639935983, 5385.246065550826, 5387.084195686664, 5400.62195646301, 5444.373897001817, 5452.0, 5472.589555783332, 5719.399463056402, 5723.685197314105, 5725.942740572625, 5728.1694154348215, 5266.0, 5311.415744268127, 5342.5993050521365, 5366.356037275474, 5414.269073675082, 4882.95117048825, 4894.730789806595, 4902.117618828645, 4907.779149938261, 4928.236123400879, 5623.775048997994, 5830.737616807254, 5861.472467477947, 5881.794137205177, 5904.498117660047, 5584.357798853021, 5604.8038318658655, 5608.747606357805, 5637.379888212211, 5700.315962084576, 5813.593048486566, 5815.912761308668, 5819.679917604572, 5837.182794408871, 5837.328707198726, 5300.098983172793, 5503.284573005483, 5541.722913854372, 5564.944350556442, 5567.777288908398, 5552.389237183826, 5617.269754931833, 5638.23883775685, 5680.9147862781265, 5721.22763426932, 5448.618596698351, 5470.234167216513, 5491.917621235605, 5509.834704958857, 5510.504054635102, 5331.893362132813, 5486.61593688438, 5488.862778430571, 5501.624593851537, 5536.667799932017, 5368.060589396762, 5378.97310513445, 5406.378266499403, 5440.673559586279, 5495.890082198253, 5550.47270509301, 5553.6390134641115, 5594.888102238014, 5599.916001260066, 5602.13166959122, 5332.733363331896, 5402.702851343214, 5409.350877894678, 5411.323584551931, 5411.756470958738], "value": 5197.0477408921515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.697335872026, 600.4835841176588, 616.3466725271213, 620.8478922663948, 642.1459458919637, 43907.78046109769, 44833.77583112084, 44951.55048449514, 45112.32331515026, 48330.758346208306, 45378.773106134424, 45965.54034459654, 46051.76974115133, 46056.309155362695, 46143.0, 9067.637294508211, 9195.0, 9197.080291970817, 9201.86197207039, 9205.493697846616, 9088.0, 9104.772380690469, 9184.724458266262, 9191.494467804268, 9204.125608067223, 9188.448693078402, 9227.723168304961, 9230.307726920475, 9233.0, 9245.44527328359, 9173.174414302712, 9207.355485116044, 9207.58565864531, 9208.401453905508, 9220.815583688322, 9017.81964360709, 9117.407368521091, 9119.133682300171, 9127.771805704844, 9137.725868223964, 43195.568044319785, 43640.563594364285, 43677.12645747067, 43700.0, 43720.12559748787, 3743.47591337213, 3819.3698039823394, 3895.8636447724193, 3929.607039296076, 4259.850905218303, 33429.6657033429, 33546.832265838515, 34327.656723432694, 34407.827960860035, 34491.31017379638, 4386.758728269944, 4393.384926110347, 4422.425084738996, 4448.777561121931, 4538.069695712404, 343284.0, 344073.0, 347679.0, 348138.0, 348291.0, 58532.41467585355, 58548.707256464295, 58974.0, 59050.704746476855, 59071.0, 38920.80539597284, 55888.16167757487, 56374.436255636916, 56464.15303770447, 56471.435285647436, 17237.224324905408, 17533.473995780147, 17614.647707046042, 17614.91192544029, 17664.116794160498, 13478.932605336911, 13562.186268823902, 13572.1856688599, 13584.524541641193, 13585.184888906697, 19598.70601940972, 19817.108230129677, 19918.203271868962, 19940.205381515443, 19975.0, 7248.383887369527, 7278.98094266803, 7279.199288078307, 7291.07631121054, 7437.85124297511, 971.9562619682131, 1016.4612755239734, 1062.5802807890839, 1071.973200669974, 1125.8198688210011, 32864.1783955403, 32995.340093198, 33348.83325583405, 33741.83129084339, 34421.311573768886, 1154.6766905266538, 1188.2929656854158, 1205.1323047405854, 1216.5004577961515, 1274.388293619068, 17487.03821289816, 17487.30050797979, 17521.7371739424, 17633.559161021076, 17642.647147057243, 615275.0, 618847.0, 620842.0, 621879.0, 626317.0, 59798.10302845461, 60537.394626053174, 60751.392486075456, 60900.390996089474, 60910.39089609136, 1215.3194211241732, 1336.8061631063636, 1366.3919555797575, 1385.4596707284168, 1385.591250581081, 32260.0, 34444.65555344414, 34599.13502162466, 34715.0, 35019.124521887155, 51599.74200128976, 52074.0, 52094.21858672199, 52244.47755522396, 52271.47728522666, 407734.0, 408914.0, 409863.0, 410285.0, 411299.0, 22502.437439064153, 28761.712382875903, 28864.567031494124, 29245.85377073186, 29840.10479685613, 47646.761766190946, 47852.0, 48634.0, 48680.0, 48820.75589622029, 40444.1911161781, 41473.1705365897, 41596.58403415927, 42117.36823947582, 43542.0, 40736.79631601823, 40895.591044089175, 41070.58929410787, 42011.579884200764, 42929.78535107304, 41558.79220603878, 41970.160596788504, 42538.0, 42556.148877022904, 42714.0, 33686.663133368354, 37026.25947480981, 37144.25711485701, 37811.0, 37814.0, 28413.289667758472, 28436.857815710788, 29011.0, 29055.7094429053, 30507.0], "value": 20684.76235445191, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.17397725741824, 401.78906074311, 405.5984575270482, 407.9694022948279, 422.33060598950664, 13744.518941837037, 18586.721199182008, 20157.496062598424, 20163.193472261093, 21417.57164856702, 15678.686426271497, 15808.920955395208, 17497.56256093598, 19986.000699965018, 19994.10026548805, 5658.405867383917, 5753.0, 5848.41515848416, 5864.0031194697, 6059.272887253535, 5942.821715348525, 5953.702314884238, 5965.164876917213, 5969.641821490703, 5982.0727787193055, 5945.524358051367, 5976.5517586180995, 5977.820665380045, 5979.073243647242, 5991.041433370661, 5950.047992321208, 5971.641701497881, 5971.671558064305, 5974.283086029661, 5977.970110149443, 5170.302009228743, 5421.457854214586, 5470.452954704498, 5530.917036244459, 5532.059549876531, 14306.21315827629, 14566.4901728439, 19263.325783597647, 23599.29202123939, 23805.880970595208, 2236.1956770869547, 2283.349245465051, 2327.8486898351553, 2333.300009996994, 2450.387403149213, 9803.90196098037, 10304.330218535772, 10655.254132210748, 10705.411202383866, 10987.780244395068, 1945.737325461073, 1967.645823751724, 1969.0716030541996, 1970.6551353513078, 1981.5399226378288, 75359.24640753577, 80101.59949200274, 81103.0, 83083.0, 83625.0, 9423.481708506099, 10062.496875156214, 10122.696319110439, 10200.846987295197, 10271.486425678686, 13767.931160344135, 13821.654458638413, 13844.030917835655, 13896.77651575743, 13911.23488208138, 7985.920140798634, 8169.305609023299, 8173.754787356389, 8234.176582341717, 8370.916290837136, 8200.774868593167, 8244.917550824535, 8303.377246706521, 8465.365097617703, 8471.407001509959, 4192.350185721218, 4267.16790225903, 4300.118475712471, 4337.696361254745, 4364.454443194601, 1974.151115020555, 1996.976093172259, 2050.1286953044896, 2061.29915828616, 2131.776163502841, 902.9413088149249, 925.9120383563551, 997.1275134257525, 1006.9043440873105, 1100.5762781329086, 19210.231590736214, 20101.798982010285, 20185.49536261576, 20386.79613203849, 20431.387058387976, 949.3212353167519, 950.6720181537358, 962.9470379129215, 989.366805244644, 992.7883297532477, 10900.34597924127, 11323.112606285344, 11367.147463940237, 11410.258333208314, 11418.371989540656, 463896.0, 470406.0, 471624.776885041, 475459.0, 477160.0, 89320.5533972326, 90603.54698226467, 91361.54319228361, 91560.0, 92477.53761231151, 935.9485228312508, 965.7006328038331, 983.4197823284154, 992.3252188511736, 995.3729150635054, 17490.387836425663, 18077.548061298046, 18280.360187393377, 18409.263629455207, 18441.446756597325, 40290.597094028686, 41496.17007659769, 42951.57048429476, 43288.0, 43858.78070609754, 58534.12198816936, 58963.70518147553, 58967.70516147392, 59212.0, 60945.0, 8742.694005710024, 8980.86528702083, 9005.504697241715, 9059.954700226457, 9360.4383736976, 7761.417893658111, 11549.769004620028, 11718.941405292919, 13583.185008899498, 13798.17210967345, 11630.244034137753, 11848.585299514476, 12009.39953002329, 12111.152219344562, 12269.202501837352, 11787.174897757075, 11919.76160476803, 11973.820392694292, 12265.448054837732, 12381.876181238073, 12227.510899563918, 12264.44809983535, 12283.69290767738, 12310.373509723871, 12506.37468126572, 7724.806879828049, 8199.180081991753, 8850.822983540422, 8874.955625221832, 9122.27021838238, 11491.94254028758, 11878.762424751629, 11919.52321907114, 11998.280103193836, 12281.570145044881], "value": 9173.354995346268, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.0636080863144, 1459.8832093432522, 1510.0109428324447, 1628.1370873437074, 1755.587436952315, 7354.301341372568, 8638.740837774869, 8905.643774249022, 8905.688300909469, 9173.541322933843, 8945.418547794388, 8954.820903581924, 8971.685990990316, 8972.685955991541, 8973.0, 2363.7636236376397, 2382.499675068229, 2385.3082606044313, 2388.22382725614, 2391.0316321889577, 2363.6690863279155, 2395.101836811196, 2441.8168637352182, 2444.6577479152934, 2444.9755502445014, 2411.131992482705, 2412.6742889709926, 2422.4065104049446, 2436.7328988925838, 2444.5966415541416, 2326.4532834783813, 2338.7193536775612, 2346.3078391874437, 2347.765223477647, 2373.8219633527556, 2301.6432452969816, 2328.2084091408833, 2328.860268383902, 2333.5216280662426, 2339.017612602711, 8901.243394311427, 8929.776755581097, 8936.910630893673, 9067.863982040275, 9076.8638470423, 5401.3248343957, 5590.440955904418, 5636.661800291996, 5638.210650508932, 5638.407967163471, 3477.3045390921916, 3487.267673788482, 3522.8590856365713, 3549.396602577568, 3574.517440145573, 1113.4098927568402, 1127.3067063755732, 1143.1026644084354, 1152.659965310227, 1171.824226366052, 12454.190477618926, 12518.874811251862, 12542.059345549029, 12554.74890502194, 12613.495460181583, 4543.25036369002, 4758.143534163814, 4853.805847766121, 4855.514448555187, 4876.317315575822, 9952.950235248778, 10004.099631033241, 10016.699499015042, 10017.69946901594, 10025.398476091457, 5508.0, 5517.36550296713, 5527.2538207342295, 5533.501984821385, 5555.944440555542, 4357.607815296638, 4494.168578812864, 4666.209941386958, 5064.18972964324, 5162.225666150032, 3793.4878791363367, 3898.2398432305426, 3933.527976642821, 4013.3979903014197, 4025.295573274665, 1130.8473356096986, 1152.7348709796793, 1164.394514852272, 1190.101473387604, 1196.5931583261734, 882.6028287270625, 1004.5178314409125, 1005.5525291245472, 1010.5452546354023, 1014.2089170447063, 6091.2081429414775, 6186.659733714599, 6278.623282603058, 6421.229452465734, 6478.676066196671, 993.6671215142864, 1019.938803671782, 1026.6243234066387, 1031.0617338222182, 1066.5360568152878, 4194.958050419456, 4228.534861165269, 4308.89227769302, 4408.096340250241, 4494.348319493655, 11302.773944521063, 11507.769844603228, 11593.072554195644, 11596.420178991017, 11600.071994240441, 11145.331280123057, 11507.07943364529, 11511.19421640477, 11520.0, 11524.135689823297, 7967.402444816662, 8148.266656000988, 8157.95921020403, 8162.510249384937, 8167.305779008732, 5773.278340207474, 5834.824955251349, 5934.19888315072, 5936.317323507852, 6029.60807547508, 9715.562799674173, 9734.41593504392, 9767.365121267096, 9844.064813842673, 9963.50182490887, 8913.197812196933, 9953.601855925683, 9975.45135017581, 10479.580816767244, 10501.0, 7626.9618651906385, 7722.806929826799, 7818.140004599376, 7875.0, 7892.763217103496, 8585.399022068395, 8660.350473714623, 8781.824363512565, 8783.341249406192, 8785.165409286108, 7656.961715191388, 7683.577403242652, 7755.72854950074, 7784.84430311402, 8019.719309824128, 7518.135414427411, 7714.0, 7784.104827944859, 7803.570803605629, 8057.879131812911, 6938.791836244921, 7058.11773528309, 7074.787756367317, 7086.255943125898, 7093.716251349888, 5208.765605547684, 5228.032813929283, 5380.946190538045, 5513.669179849061, 5526.889462210814, 5167.509086636764, 5237.738113094406, 5283.0, 5391.649542779707, 5725.112607545837], "value": 4722.875223880847, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.61561441943826, 341.5082281514619, 352.83945804658873, 352.86238367036844, 352.88354842901845, 5197.194434862593, 5253.212018197272, 5369.4362091980365, 5402.189671549265, 5417.322834645669, 5179.119549676555, 5215.1655735082395, 5217.47825217478, 5233.921491177635, 5249.842504724865, 4123.752574845504, 4138.441310423092, 4141.751494910315, 4148.108156746299, 4166.208420400114, 3934.645881870631, 4069.3489041753323, 4146.5853414658595, 4156.729812562189, 4164.75011499311, 4066.207089617526, 4069.674426045924, 4070.2266569351877, 4072.8370865165357, 4078.8368465261356, 4046.6358027777496, 4049.4330793688905, 4055.1281474482835, 4077.938830917539, 4084.7140700151003, 3510.8946731598094, 3603.2433189030335, 3621.185233322508, 3629.818509074562, 3651.3427583035045, 5142.125838607446, 5149.459306772772, 5156.535911767922, 5171.95707695462, 5189.948100518985, 4700.130475861943, 4736.123817093849, 4755.619550435991, 4756.453007904066, 4757.809687612491, 5336.3724064160415, 5394.32570928634, 5465.72671366434, 5483.340579818785, 5500.614956953015, 3974.5826688197612, 3977.962368196283, 3985.720999530034, 3985.860494882665, 3990.9600903990877, 5695.231143795576, 5722.97138514309, 5728.627639203439, 5729.226554415184, 5732.168835518868, 4043.272211001989, 4077.2660921033907, 4096.897577560525, 4103.835846566164, 4117.444145040441, 4126.0, 4127.482235836138, 4127.834886604563, 4134.152498737752, 4137.862087925844, 4676.3453116563705, 4695.929561056587, 4700.459447163552, 4713.552212539872, 4714.363560919301, 5356.5179133877355, 5490.80782172622, 5504.972475137599, 5517.36550296721, 5584.022796010682, 1352.1413902172192, 1388.94444222232, 1389.6456403617215, 1391.1166409330149, 1396.8114304568956, 972.7470857577081, 990.9207263418909, 995.4723996281982, 1002.4787110702393, 1008.7326858382535, 5321.148616221463, 5353.518183363516, 5355.625106242525, 5385.676859388449, 5405.5135037846785, 5527.407185329568, 5527.50252477279, 5576.888462230732, 5607.130894711245, 5640.379558248589, 5220.791168353223, 5248.291480650063, 5254.922740838119, 5285.814996475105, 5290.28581141541, 2608.608708693673, 2622.645942797736, 2625.448655782269, 2629.7238789926782, 2633.3548280671357, 4805.903881922342, 5041.7227052511735, 5111.881752131197, 5117.590592752571, 5147.819826306061, 5008.549230569266, 5014.495651304594, 5038.874028149252, 5063.455646027923, 5071.87320316995, 4864.24887040658, 5100.974495127501, 5104.719240441811, 5113.0, 5160.767765450564, 4905.7792399342825, 4915.877103072452, 4917.877053073702, 4919.114559379346, 5000.0, 4900.583450406755, 4907.435644900882, 4913.361263035926, 4925.408950925912, 4930.580900623344, 5057.898842023065, 5058.797648094035, 5092.060701119908, 5114.514121158484, 5420.783168673209, 4042.3128068227884, 4079.4905884822806, 4080.510338759368, 4119.867036564969, 4126.401671757519, 5653.406392328746, 5685.9530568994305, 5717.971410142923, 5731.19763233156, 5758.395368486416, 5191.195364718475, 5479.917801233064, 5595.356533998643, 5609.859753506195, 5615.719214039364, 5221.765020574159, 5484.462521673578, 5502.642328248651, 5508.504234618743, 5511.972440137934, 5302.946970530245, 5445.237666726583, 5455.618106732491, 5459.481349271813, 5484.533814625802, 5580.888382232251, 5581.957832981117, 5583.218349431002, 5602.03928624978, 5603.0, 5174.456682048331, 5418.884141618664, 5425.647332923348, 5426.399212232322, 5438.428964958623], "value": 4117.861475040096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.14997028165, 814.9551774652394, 846.6020970144037, 847.3856454070799, 859.8581234096371, 15611.765823512642, 16944.0, 17287.222075006623, 17298.13509324535, 18177.545561360974, 17101.486955391327, 17369.913150434262, 17470.0, 17479.82520174798, 17490.912545437255, 812.9878051829213, 832.2218725491655, 834.5827086456771, 835.2315869400167, 835.3526017336552, 673.1551902362528, 793.3851265269404, 816.420341557492, 818.5129847740615, 833.2708879730236, 871.716692075075, 877.188600544497, 878.0122362342365, 880.3705350674267, 880.9559522023873, 860.5525126933959, 867.9132086791334, 868.1882439918696, 868.5743985447149, 868.6134670071821, 505.24465923444683, 606.372404561277, 623.5416968528128, 633.882731694638, 637.7449020391834, 15963.840361596467, 16005.679886402324, 16028.118453485054, 16031.35874565016, 16049.438269660623, 14280.571582852532, 14730.852691473056, 14772.70454590913, 14903.925480372638, 15162.09027458345, 14044.438222471088, 14066.367013484418, 14125.434982600684, 14243.928780356135, 14912.776308355384, 4577.450705915278, 4590.9540904591195, 4602.355670206174, 4707.176244157293, 4833.275008748681, 66202.3379766201, 66725.66637166831, 66978.33021669819, 75205.62397188034, 87780.5610971941, 11261.772494673847, 11296.887031129583, 11319.830202546967, 11449.313041217554, 11561.710957226136, 21774.673379899315, 21844.12623495074, 21848.563028739336, 21984.0, 22018.229361972255, 8049.6377663005305, 8134.511929284144, 8157.388195885333, 8395.28640065589, 8471.49171049739, 4501.347304640809, 4566.178087944202, 4584.358189853423, 4719.83480578185, 4772.665913386099, 3532.6467353264984, 3639.8180090995343, 3648.416253399443, 3651.507046548735, 3663.908402289964, 651.9674016299166, 654.3718030690591, 661.0612929639923, 667.3793372163934, 698.0924797762888, 753.3596443023496, 758.3402439877342, 781.9648115834801, 803.9075506316732, 814.5479259011306, 10148.340357876861, 10883.499307196207, 10912.672619821418, 11051.944740276407, 11182.16133789969, 739.9112106547249, 786.194150995223, 793.8610743119931, 796.1839114907266, 802.0495712580611, 4758.809647614126, 5216.06110900034, 5310.230016647642, 5338.759755810997, 5344.67931924078, 125272.74727252794, 126932.36533817457, 130111.0, 133141.3342933279, 135731.32134339464, 102087.48956255171, 107439.46280268549, 110591.0, 112119.4394028041, 114547.0, 58614.0, 58648.0, 96821.0, 96833.51583242038, 100200.49899750455, 9561.808763824823, 9872.506374681381, 10028.690532124236, 10085.747856303358, 10207.33652312627, 55738.72130639321, 56041.86394218789, 56077.158842616576, 57211.0, 57769.0, 76287.56112865162, 77571.61214193892, 78033.63596368472, 79866.0, 81055.59472202601, 12385.318807465675, 12932.612021639367, 13083.869161308265, 13187.274699891597, 13610.115342502706, 14266.559399302943, 14490.565283041526, 14618.41526338977, 14686.192259425834, 14703.779443308573, 13616.38726257301, 14076.507322243673, 14140.15159090458, 14234.572962811133, 14680.26598670041, 13624.93187534056, 14031.158130512202, 14222.217778022308, 14438.205898675673, 14656.0, 13876.23680697531, 13894.305284735925, 13920.234387108401, 14124.2937853109, 14395.568132956028, 10775.730606734895, 11177.0, 11187.272827266203, 11321.150913681342, 11394.37330946806, 9271.953640231755, 10554.2084343673, 11263.875249233739, 11556.364399958084, 11744.0], "value": 7921.54918839563, "unit": "score"}], "name": "dromaeo_css", "value": 7304.859466154763, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0, 109.0, 114.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 119.0, 117.0, 173.0, 123.0, 119.0, 154.0, 123.0, 156.0, 121.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 159.0, 158.0, 160.0, 159.0, 160.0, 161.0, 160.0, 161.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 120.0, 75.0, 75.0, 75.0, 74.0, 99.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 133.0, 84.0, 84.0, 84.0, 121.0, 83.0, 83.0, 131.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 49.0, 55.0, 51.0, 50.0, 49.0, 50.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 60.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 76.0, 74.0, 75.0, 75.0, 85.0, 81.0, 84.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 123.0, 120.0, 120.0, 127.0, 121.0, 122.0, 134.0, 161.0, 128.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 157.0, 159.0, 158.0, 156.0, 163.0, 159.0, 163.0, 160.0, 159.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1481.2, "alertThreshold": 2.0}]} 19:45:53 INFO - Return code: 0 19:45:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:45:53 INFO - # TBPL SUCCESS # 19:45:53 INFO - Running post-action listener: _resource_record_post_action 19:45:53 INFO - [mozharness: 2016-08-08 02:45:53.667688Z] Finished run-tests step (success) 19:45:53 INFO - Running post-run listener: _resource_record_post_run 19:45:53 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 557092864; Read time: 212; Write time: 481440 19:45:53 INFO - TinderboxPrint: CPU usage
13.0% 19:45:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 212 19:45:53 INFO - TinderboxPrint: I/O write bytes / time
557,092,864 / 481,440 19:45:53 INFO - TinderboxPrint: CPU idle
8,271.7 (86.9%) 19:45:53 INFO - TinderboxPrint: CPU user
1,197.3 (12.6%) 19:45:53 INFO - TinderboxPrint: Swap in / out
0 / 0 19:45:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 261713920; Read time: 140; Write time: 270852 19:45:53 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295378944; Read time: 60; Write time: 210588 19:45:54 INFO - Running post-run listener: _upload_blobber_files 19:45:54 INFO - Blob upload gear active. 19:45:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:45:54 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 19:45:54 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'] 19:45:54 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 19:45:54 INFO - (blobuploader) - INFO - Open directory for files ... 19:45:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:45:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:45:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:45:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:45:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:45:55 INFO - (blobuploader) - INFO - Done attempting. 19:45:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:45:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:45:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:45:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:45:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:45:56 INFO - (blobuploader) - INFO - Done attempting. 19:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:45:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:45:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:45:59 INFO - (blobuploader) - INFO - Done attempting. 19:45:59 INFO - (blobuploader) - INFO - Iteration through files over. 19:45:59 INFO - Return code: 0 19:45:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:45:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:45:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/67aedf74157eaf8c0683c0c41f59b7483885f2e4f10f61f0db5330f548ff4e0561169a1915aaaa42ed7efbedb1fdad6cee126092c18bd3ff89e2ca5d1f8cfd9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b484fd2cbcb57f90ad416410b0e09b2556a26c6261fd9dc5020df13d29227fd8025a60b1e4c76971a9a2699d636be58aa257eb57d56a4be3d29a5d8e8ef3fcc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6a522695e7aefde5c5f178ed86b85a8cbafdea52709a890716b3d04880801af1d0311f241e6147e1d86cf3626732a1f8b11f03c4d3e166c840d0e8dc4538081"} 19:45:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:45:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:45:59 INFO - Contents: 19:45:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/67aedf74157eaf8c0683c0c41f59b7483885f2e4f10f61f0db5330f548ff4e0561169a1915aaaa42ed7efbedb1fdad6cee126092c18bd3ff89e2ca5d1f8cfd9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b484fd2cbcb57f90ad416410b0e09b2556a26c6261fd9dc5020df13d29227fd8025a60b1e4c76971a9a2699d636be58aa257eb57d56a4be3d29a5d8e8ef3fcc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6a522695e7aefde5c5f178ed86b85a8cbafdea52709a890716b3d04880801af1d0311f241e6147e1d86cf3626732a1f8b11f03c4d3e166c840d0e8dc4538081"} 19:45:59 INFO - Running post-run listener: copy_logs_to_upload_dir 19:45:59 INFO - Copying logs to upload dir... 19:45:59 INFO - mkdir: /builds/slave/test/build/upload/logs 19:45:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.146791 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-08-07 19:45:58.749288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 19:45:58.753083) ========= 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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 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/67aedf74157eaf8c0683c0c41f59b7483885f2e4f10f61f0db5330f548ff4e0561169a1915aaaa42ed7efbedb1fdad6cee126092c18bd3ff89e2ca5d1f8cfd9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b484fd2cbcb57f90ad416410b0e09b2556a26c6261fd9dc5020df13d29227fd8025a60b1e4c76971a9a2699d636be58aa257eb57d56a4be3d29a5d8e8ef3fcc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6a522695e7aefde5c5f178ed86b85a8cbafdea52709a890716b3d04880801af1d0311f241e6147e1d86cf3626732a1f8b11f03c4d3e166c840d0e8dc4538081"} build_url:https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/fhojc72iTZujh0cNXA6cpg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/67aedf74157eaf8c0683c0c41f59b7483885f2e4f10f61f0db5330f548ff4e0561169a1915aaaa42ed7efbedb1fdad6cee126092c18bd3ff89e2ca5d1f8cfd9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b484fd2cbcb57f90ad416410b0e09b2556a26c6261fd9dc5020df13d29227fd8025a60b1e4c76971a9a2699d636be58aa257eb57d56a4be3d29a5d8e8ef3fcc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6a522695e7aefde5c5f178ed86b85a8cbafdea52709a890716b3d04880801af1d0311f241e6147e1d86cf3626732a1f8b11f03c4d3e166c840d0e8dc4538081"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 19:45:58.781138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:45:58.781659) ========= 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=0333a256a5e4f052382dd180000001b1-1470613804.859670-80755837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 19:45:58.864740) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 19:45:58.865084) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 19:45:58.865411) ========= ========= Total master_lag: 0.20 =========