builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1472654239.69 results: success (0) buildid: 20160831061621 builduid: ed1ea8338b1f471a85ea9d1b844a5abd revision: 3d9cabea1e561a62734fa8d4ce8005696ef33f6d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.693524) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.693999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.694290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.708995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.709274) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 07:37:20-- 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% 114M=0s 2016-08-31 07:37:20 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.814264) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.814635) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018909 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.865146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:19.865498) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d9cabea1e561a62734fa8d4ce8005696ef33f6d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3d9cabea1e561a62734fa8d4ce8005696ef33f6d --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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 07:37:21,312 truncating revision to first 12 chars 2016-08-31 07:37:21,313 Setting DEBUG logging. 2016-08-31 07:37:21,313 attempt 1/10 2016-08-31 07:37:21,313 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d9cabea1e56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 07:37:21,583 unpacking tar archive at: mozilla-central-3d9cabea1e56/testing/mozharness/ program finished with exit code 0 elapsedTime=0.554040 ========= master_lag: 0.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:20.747829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:20.751014) ========= script_repo_revision: 3d9cabea1e561a62734fa8d4ce8005696ef33f6d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:20.751456) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:20.751729) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 07:37:20.761828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 07:37:20.762186) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:37:21 INFO - MultiFileLogger online at 20160831 07:37:21 in /builds/slave/test 07:37:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:37:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:37:21 INFO - {'append_to_log': False, 07:37:21 INFO - 'base_work_dir': '/builds/slave/test', 07:37:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:37:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:37:21 INFO - 'branch': 'Firefox-Non-PGO', 07:37:21 INFO - 'buildbot_json_path': 'buildprops.json', 07:37:21 INFO - 'config_files': ('talos/linux_config.py',), 07:37:21 INFO - 'default_actions': ('clobber', 07:37:21 INFO - 'read-buildbot-config', 07:37:21 INFO - 'download-and-extract', 07:37:21 INFO - 'populate-webroot', 07:37:21 INFO - 'create-virtualenv', 07:37:21 INFO - 'install', 07:37:21 INFO - 'run-tests'), 07:37:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:37:21 INFO - 'download_minidump_stackwalk': True, 07:37:21 INFO - 'download_symbols': 'ondemand', 07:37:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:37:21 INFO - 'tooltool.py': '/tools/tooltool.py', 07:37:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:37:21 INFO - '/tools/misc-python/virtualenv.py')}, 07:37:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:37:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:37:21 INFO - 'installer_path': 'installer.exe', 07:37:21 INFO - 'log_level': 'info', 07:37:21 INFO - 'log_name': 'talos', 07:37:21 INFO - 'log_to_console': True, 07:37:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:37:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:37:21 INFO - 'opt_config_files': (), 07:37:21 INFO - 'pip_index': False, 07:37:21 INFO - 'sps_profile': False, 07:37:21 INFO - 'sps_profile_interval': 0, 07:37:21 INFO - 'suite': 'dromaeojs', 07:37:21 INFO - 'system_bits': '32', 07:37:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:37:21 INFO - 'title': 'talos-linux64-ix-066', 07:37:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:37:21 INFO - 'use_talos_json': True, 07:37:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:37:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:37:21 INFO - 'work_dir': 'build'} 07:37:21 INFO - [mozharness: 2016-08-31 14:37:21.999610Z] Running clobber step. 07:37:21 INFO - Running pre-action listener: _resource_record_pre_action 07:37:21 INFO - Running main action method: clobber 07:37:22 INFO - rmtree: /builds/slave/test/build 07:37:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:37:22 INFO - Running post-action listener: _resource_record_post_action 07:37:22 INFO - [mozharness: 2016-08-31 14:37:22.458553Z] Finished clobber step (success) 07:37:22 INFO - [mozharness: 2016-08-31 14:37:22.458658Z] Running read-buildbot-config step. 07:37:22 INFO - Running pre-action listener: _resource_record_pre_action 07:37:22 INFO - Running main action method: read_buildbot_config 07:37:22 INFO - Using buildbot properties: 07:37:22 INFO - { 07:37:22 INFO - "project": "", 07:37:22 INFO - "product": "firefox", 07:37:22 INFO - "script_repo_revision": "production", 07:37:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:37:22 INFO - "repository": "", 07:37:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:37:22 INFO - "buildid": "20160831061621", 07:37:22 INFO - "pgo_build": "False", 07:37:22 INFO - "basedir": "/builds/slave/test", 07:37:22 INFO - "buildnumber": 197, 07:37:22 INFO - "slavename": "talos-linux64-ix-066", 07:37:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:37:22 INFO - "platform": "ubuntu64_hw", 07:37:22 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:37:22 INFO - "branch": "mozilla-central", 07:37:22 INFO - "repo_path": "mozilla-central", 07:37:22 INFO - "revision": "3d9cabea1e561a62734fa8d4ce8005696ef33f6d", 07:37:22 INFO - "stage_platform": "linux64", 07:37:22 INFO - "builduid": "ed1ea8338b1f471a85ea9d1b844a5abd", 07:37:22 INFO - "slavebuilddir": "test" 07:37:22 INFO - } 07:37:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:37:22 INFO - Running post-action listener: _resource_record_post_action 07:37:22 INFO - [mozharness: 2016-08-31 14:37:22.461112Z] Finished read-buildbot-config step (success) 07:37:22 INFO - [mozharness: 2016-08-31 14:37:22.461203Z] Running download-and-extract step. 07:37:22 INFO - Running pre-action listener: _resource_record_pre_action 07:37:22 INFO - Running main action method: download_and_extract 07:37:22 INFO - mkdir: /builds/slave/test/build/tests 07:37:22 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')]} 07:37:22 INFO - trying https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:23 INFO - Downloaded 1361 bytes. 07:37:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:37:23 INFO - Using the following test package requirements: 07:37:23 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:37:23 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:37:23 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:37:23 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'jsshell-linux-x86_64.zip'], 07:37:23 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:37:23 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:37:23 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:37:23 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:37:23 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:37:23 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:37:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:37:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:23 INFO - trying https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:37:23 INFO - Downloading https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:24 INFO - Downloaded 20331897 bytes. 07:37:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:37:28 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 07:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:28 INFO - trying https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:37:28 INFO - Downloading https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:29 INFO - Downloaded 20331897 bytes. 07:37:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:37: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')]} 07:37:33 INFO - trying https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:37:33 INFO - Downloading https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:34 INFO - Downloaded 14413998 bytes. 07:37:34 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 07:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:34 INFO - trying https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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 07:37:35 INFO - Downloaded 55856549 bytes. 07:37:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:37:35 INFO - mkdir: /builds/slave/test/properties 07:37:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:37:35 INFO - Writing to file /builds/slave/test/properties/build_url 07:37:35 INFO - Contents: 07:37:35 INFO - build_url:https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:37:36 INFO - Running post-action listener: _resource_record_post_action 07:37:36 INFO - Running post-action listener: set_extra_try_arguments 07:37:36 INFO - [mozharness: 2016-08-31 14:37:36.545362Z] Finished download-and-extract step (success) 07:37:36 INFO - [mozharness: 2016-08-31 14:37:36.545590Z] Running populate-webroot step. 07:37:36 INFO - Running pre-action listener: _resource_record_pre_action 07:37:36 INFO - Running main action method: populate_webroot 07:37:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:37:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:37:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:37:36 INFO - '--tppagecycles', 07:37:36 INFO - '7'], 07:37:36 INFO - 'tests': ['tsvgm']}}, 07:37:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:37:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:37:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:37:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:37:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:37:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:37:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['damp', 'tps']}, 07:37:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'tests': ['damp', 'tps']}, 07:37:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['dromaeo_dom']}, 07:37:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:37:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['basic_compositor_video']}, 07:37:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:37:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'other-e10s': {'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['a11yr', 07:37:36 INFO - 'ts_paint', 07:37:36 INFO - 'tpaint', 07:37:36 INFO - 'sessionrestore', 07:37:36 INFO - 'sessionrestore_no_auto_restore', 07:37:36 INFO - 'tabpaint']}, 07:37:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['tsvgx', 07:37:36 INFO - 'tsvgr_opacity', 07:37:36 INFO - 'tart', 07:37:36 INFO - 'tscrollx', 07:37:36 INFO - 'cart']}, 07:37:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:37:36 INFO - 'tsvgr_opacity', 07:37:36 INFO - 'tart', 07:37:36 INFO - 'tscrollx', 07:37:36 INFO - 'cart']}, 07:37:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'talos_options': ['--disable-e10s'], 07:37:36 INFO - 'tests': ['tp5o']}, 07:37:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'tests': ['tp5o']}, 07:37:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'talos_options': ['--disable-e10s', 07:37:36 INFO - '--xperf_path', 07:37:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:37:36 INFO - 'tests': ['tp5n']}, 07:37:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:37:36 INFO - 'talos_options': ['--xperf_path', 07:37:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:37:36 INFO - 'tests': ['tp5n']}}, 07:37:36 INFO - 'talos.zip': {'path': '', 07:37:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:37:36 INFO - Running post-action listener: _resource_record_post_action 07:37:36 INFO - [mozharness: 2016-08-31 14:37:36.562647Z] Finished populate-webroot step (success) 07:37:36 INFO - [mozharness: 2016-08-31 14:37:36.562738Z] Running create-virtualenv step. 07:37:36 INFO - Running pre-action listener: _resource_record_pre_action 07:37:36 INFO - Running main action method: create_virtualenv 07:37:36 INFO - Creating virtualenv /builds/slave/test/build/venv 07:37:36 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 07:37:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:37:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:37:36 INFO - Using real prefix '/usr' 07:37:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:37:37 INFO - Installing distribute.............................................................................................................................................................................................done. 07:37:39 INFO - Installing pip.................done. 07:37:39 INFO - Return code: 0 07:37:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:37:39 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')]} 07:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 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')]} 07:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:39 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 07:37:39 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 07:37:39 INFO - Using env: {'DISPLAY': ':0', 07:37:39 INFO - 'HOME': '/home/cltbld', 07:37:39 INFO - 'LANG': 'en_US.UTF-8', 07:37:39 INFO - 'LANGUAGE': 'en_US:en', 07:37:39 INFO - 'LOGNAME': 'cltbld', 07:37:39 INFO - 'MAIL': '/var/mail/cltbld', 07:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:39 INFO - 'MOZ_NO_REMOTE': '1', 07:37:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:39 INFO - 'NO_EM_RESTART': '1', 07:37:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:39 INFO - 'PWD': '/builds/slave/test', 07:37:39 INFO - 'SHELL': '/bin/bash', 07:37:39 INFO - 'SHLVL': '1', 07:37:39 INFO - 'TERM': 'linux', 07:37:39 INFO - 'TMOUT': '86400', 07:37:39 INFO - 'USER': 'cltbld', 07:37:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:39 INFO - '_': '/tools/buildbot/bin/python'} 07:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:39 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 07:37:39 INFO - Cleaning up... 07:37:39 INFO - Return code: 0 07:37:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:37:39 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')]} 07:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 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')]} 07:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:39 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:39 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 07:37:39 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 07:37:39 INFO - Using env: {'DISPLAY': ':0', 07:37:39 INFO - 'HOME': '/home/cltbld', 07:37:39 INFO - 'LANG': 'en_US.UTF-8', 07:37:39 INFO - 'LANGUAGE': 'en_US:en', 07:37:39 INFO - 'LOGNAME': 'cltbld', 07:37:39 INFO - 'MAIL': '/var/mail/cltbld', 07:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:39 INFO - 'MOZ_NO_REMOTE': '1', 07:37:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:39 INFO - 'NO_EM_RESTART': '1', 07:37:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:39 INFO - 'PWD': '/builds/slave/test', 07:37:39 INFO - 'SHELL': '/bin/bash', 07:37:39 INFO - 'SHLVL': '1', 07:37:39 INFO - 'TERM': 'linux', 07:37:39 INFO - 'TMOUT': '86400', 07:37:39 INFO - 'USER': 'cltbld', 07:37:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:39 INFO - '_': '/tools/buildbot/bin/python'} 07:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:39 INFO - Downloading/unpacking psutil>=3.1.1 07:37:39 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 07:37:39 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 07:37:39 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 07:37:39 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 07:37:39 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 07:37:39 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 07:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:37:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:41 INFO - Installing collected packages: psutil 07:37:41 INFO - Running setup.py install for psutil 07:37:41 INFO - building 'psutil._psutil_linux' extension 07:37:41 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 07:37:41 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 07:37:41 INFO - building 'psutil._psutil_posix' extension 07:37:41 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 07:37:41 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 07:37:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:42 INFO - Successfully installed psutil 07:37:42 INFO - Cleaning up... 07:37:42 INFO - Return code: 0 07:37:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:37: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 mozsystemmonitor==0.3 07:37:42 INFO - Using env: {'DISPLAY': ':0', 07:37:42 INFO - 'HOME': '/home/cltbld', 07:37:42 INFO - 'LANG': 'en_US.UTF-8', 07:37:42 INFO - 'LANGUAGE': 'en_US:en', 07:37:42 INFO - 'LOGNAME': 'cltbld', 07:37:42 INFO - 'MAIL': '/var/mail/cltbld', 07:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:42 INFO - 'MOZ_NO_REMOTE': '1', 07:37:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:42 INFO - 'NO_EM_RESTART': '1', 07:37:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:42 INFO - 'PWD': '/builds/slave/test', 07:37:42 INFO - 'SHELL': '/bin/bash', 07:37:42 INFO - 'SHLVL': '1', 07:37:42 INFO - 'TERM': 'linux', 07:37:42 INFO - 'TMOUT': '86400', 07:37:42 INFO - 'USER': 'cltbld', 07:37:42 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:42 INFO - '_': '/tools/buildbot/bin/python'} 07:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:37:44 INFO - Installing collected packages: mozsystemmonitor 07:37:44 INFO - Running setup.py install for mozsystemmonitor 07:37:44 INFO - Successfully installed mozsystemmonitor 07:37:44 INFO - Cleaning up... 07:37:44 INFO - Return code: 0 07:37:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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', '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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:37: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 jsonschema==2.5.1 07:37:44 INFO - Using env: {'DISPLAY': ':0', 07:37:44 INFO - 'HOME': '/home/cltbld', 07:37:44 INFO - 'LANG': 'en_US.UTF-8', 07:37:44 INFO - 'LANGUAGE': 'en_US:en', 07:37:44 INFO - 'LOGNAME': 'cltbld', 07:37:44 INFO - 'MAIL': '/var/mail/cltbld', 07:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:44 INFO - 'MOZ_NO_REMOTE': '1', 07:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:44 INFO - 'NO_EM_RESTART': '1', 07:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:44 INFO - 'PWD': '/builds/slave/test', 07:37:44 INFO - 'SHELL': '/bin/bash', 07:37:44 INFO - 'SHLVL': '1', 07:37:44 INFO - 'TERM': 'linux', 07:37:44 INFO - 'TMOUT': '86400', 07:37:44 INFO - 'USER': 'cltbld', 07:37:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:44 INFO - '_': '/tools/buildbot/bin/python'} 07:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:44 INFO - Downloading/unpacking jsonschema==2.5.1 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:44 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 07:37:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:37:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:37:46 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 07:37:46 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 07:37:46 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 07:37:46 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 07:37:46 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 07:37:46 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 07:37:46 INFO - Downloading functools32-3.2.3-2.tar.gz 07:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:37:46 INFO - warning: no files found matching '*.txt' 07:37:46 INFO - no previously-included directories found matching 'build' 07:37:46 INFO - no previously-included directories found matching 'dist' 07:37:46 INFO - no previously-included directories found matching '.git*' 07:37:46 INFO - Installing collected packages: jsonschema, functools32 07:37:46 INFO - Running setup.py install for functools32 07:37:46 INFO - warning: no files found matching '*.txt' 07:37:46 INFO - no previously-included directories found matching 'build' 07:37:46 INFO - no previously-included directories found matching 'dist' 07:37:46 INFO - no previously-included directories found matching '.git*' 07:37:46 INFO - Successfully installed jsonschema functools32 07:37:46 INFO - Cleaning up... 07:37:46 INFO - Return code: 0 07:37:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:37:46 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')]} 07:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:46 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')]} 07:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:46 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:46 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 07:37:46 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 07:37:46 INFO - Using env: {'DISPLAY': ':0', 07:37:46 INFO - 'HOME': '/home/cltbld', 07:37:46 INFO - 'LANG': 'en_US.UTF-8', 07:37:46 INFO - 'LANGUAGE': 'en_US:en', 07:37:46 INFO - 'LOGNAME': 'cltbld', 07:37:46 INFO - 'MAIL': '/var/mail/cltbld', 07:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:46 INFO - 'MOZ_NO_REMOTE': '1', 07:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:46 INFO - 'NO_EM_RESTART': '1', 07:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:46 INFO - 'PWD': '/builds/slave/test', 07:37:46 INFO - 'SHELL': '/bin/bash', 07:37:46 INFO - 'SHLVL': '1', 07:37:46 INFO - 'TERM': 'linux', 07:37:46 INFO - 'TMOUT': '86400', 07:37:46 INFO - 'USER': 'cltbld', 07:37:46 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:46 INFO - '_': '/tools/buildbot/bin/python'} 07:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:37:47 INFO - Cleaning up... 07:37:47 INFO - Return code: 0 07:37:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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', '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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:37: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 blobuploader==1.2.4 07:37:47 INFO - Using env: {'DISPLAY': ':0', 07:37:47 INFO - 'HOME': '/home/cltbld', 07:37:47 INFO - 'LANG': 'en_US.UTF-8', 07:37:47 INFO - 'LANGUAGE': 'en_US:en', 07:37:47 INFO - 'LOGNAME': 'cltbld', 07:37:47 INFO - 'MAIL': '/var/mail/cltbld', 07:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:47 INFO - 'MOZ_NO_REMOTE': '1', 07:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:47 INFO - 'NO_EM_RESTART': '1', 07:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:47 INFO - 'PWD': '/builds/slave/test', 07:37:47 INFO - 'SHELL': '/bin/bash', 07:37:47 INFO - 'SHLVL': '1', 07:37:47 INFO - 'TERM': 'linux', 07:37:47 INFO - 'TMOUT': '86400', 07:37:47 INFO - 'USER': 'cltbld', 07:37:47 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:47 INFO - '_': '/tools/buildbot/bin/python'} 07:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:47 INFO - Downloading/unpacking blobuploader==1.2.4 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:49 INFO - Downloading blobuploader-1.2.4.tar.gz 07:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:37:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:37:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:50 INFO - Downloading docopt-0.6.1.tar.gz 07:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:37:50 INFO - Installing collected packages: blobuploader, requests, docopt 07:37:50 INFO - Running setup.py install for blobuploader 07:37:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:37:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:37:50 INFO - Running setup.py install for requests 07:37:50 INFO - Running setup.py install for docopt 07:37:50 INFO - Successfully installed blobuploader requests docopt 07:37:50 INFO - Cleaning up... 07:37:50 INFO - Return code: 0 07:37:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:50 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:50 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 07:37:50 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 07:37:50 INFO - Using env: {'DISPLAY': ':0', 07:37:50 INFO - 'HOME': '/home/cltbld', 07:37:50 INFO - 'LANG': 'en_US.UTF-8', 07:37:50 INFO - 'LANGUAGE': 'en_US:en', 07:37:50 INFO - 'LOGNAME': 'cltbld', 07:37:50 INFO - 'MAIL': '/var/mail/cltbld', 07:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:50 INFO - 'MOZ_NO_REMOTE': '1', 07:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:50 INFO - 'NO_EM_RESTART': '1', 07:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:50 INFO - 'PWD': '/builds/slave/test', 07:37:50 INFO - 'SHELL': '/bin/bash', 07:37:50 INFO - 'SHLVL': '1', 07:37:50 INFO - 'TERM': 'linux', 07:37:50 INFO - 'TMOUT': '86400', 07:37:50 INFO - 'USER': 'cltbld', 07:37:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:50 INFO - '_': '/tools/buildbot/bin/python'} 07:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:50 INFO - Running setup.py (path:/tmp/pip-KeNY30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:51 INFO - Running setup.py (path:/tmp/pip-8MsGZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:51 INFO - Running setup.py (path:/tmp/pip-qX41XP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:51 INFO - Running setup.py (path:/tmp/pip-U25k3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:51 INFO - Running setup.py (path:/tmp/pip-JvdJ7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:51 INFO - Running setup.py (path:/tmp/pip-lRziin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:51 INFO - Running setup.py (path:/tmp/pip-5vVL9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:51 INFO - Running setup.py (path:/tmp/pip-Jwc8Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:51 INFO - Running setup.py (path:/tmp/pip-EB89SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:51 INFO - Running setup.py (path:/tmp/pip-Nnca8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:51 INFO - Running setup.py (path:/tmp/pip-hlcLNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:51 INFO - Running setup.py (path:/tmp/pip-m4GxrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:51 INFO - Running setup.py (path:/tmp/pip-xCumDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:51 INFO - Running setup.py (path:/tmp/pip-LcSQMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:52 INFO - Running setup.py (path:/tmp/pip-vyLIBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:52 INFO - Running setup.py (path:/tmp/pip-s_MQOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:52 INFO - Running setup.py (path:/tmp/pip-ISxo1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:37:52 INFO - Running setup.py install for manifestparser 07:37:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:37:52 INFO - Running setup.py install for mozcrash 07:37:52 INFO - Running setup.py install for mozdebug 07:37:52 INFO - Running setup.py install for mozdevice 07:37:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:37:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:37:52 INFO - Running setup.py install for mozfile 07:37:52 INFO - Running setup.py install for mozhttpd 07:37:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:37:52 INFO - Running setup.py install for mozinfo 07:37:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:37:52 INFO - Running setup.py install for mozInstall 07:37:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Running setup.py install for mozleak 07:37:53 INFO - Running setup.py install for mozlog 07:37:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Running setup.py install for moznetwork 07:37:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Running setup.py install for mozprocess 07:37:53 INFO - Running setup.py install for mozprofile 07:37:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Running setup.py install for mozrunner 07:37:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Running setup.py install for mozscreenshot 07:37:53 INFO - Running setup.py install for moztest 07:37:53 INFO - Running setup.py install for mozversion 07:37:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:37:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:37:53 INFO - Cleaning up... 07:37:54 INFO - Return code: 0 07:37:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:54 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')]} 07:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:54 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')]} 07:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:54 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:54 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 07:37:54 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 07:37:54 INFO - Using env: {'DISPLAY': ':0', 07:37:54 INFO - 'HOME': '/home/cltbld', 07:37:54 INFO - 'LANG': 'en_US.UTF-8', 07:37:54 INFO - 'LANGUAGE': 'en_US:en', 07:37:54 INFO - 'LOGNAME': 'cltbld', 07:37:54 INFO - 'MAIL': '/var/mail/cltbld', 07:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:54 INFO - 'MOZ_NO_REMOTE': '1', 07:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:54 INFO - 'NO_EM_RESTART': '1', 07:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:54 INFO - 'PWD': '/builds/slave/test', 07:37:54 INFO - 'SHELL': '/bin/bash', 07:37:54 INFO - 'SHLVL': '1', 07:37:54 INFO - 'TERM': 'linux', 07:37:54 INFO - 'TMOUT': '86400', 07:37:54 INFO - 'USER': 'cltbld', 07:37:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:54 INFO - '_': '/tools/buildbot/bin/python'} 07:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:54 INFO - Running setup.py (path:/tmp/pip-Tm2FCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:54 INFO - Running setup.py (path:/tmp/pip-KJopIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:54 INFO - Running setup.py (path:/tmp/pip-T1gBHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:54 INFO - Running setup.py (path:/tmp/pip-3pR8yZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:54 INFO - Running setup.py (path:/tmp/pip-lByMDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:54 INFO - Running setup.py (path:/tmp/pip-Jo1Vcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:54 INFO - Running setup.py (path:/tmp/pip-HOMH2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:54 INFO - Running setup.py (path:/tmp/pip-A45srl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:54 INFO - Running setup.py (path:/tmp/pip-RGKML6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:54 INFO - Running setup.py (path:/tmp/pip-XA8Vdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:54 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)) 07:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:54 INFO - Running setup.py (path:/tmp/pip-oz4D7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:55 INFO - Running setup.py (path:/tmp/pip-EbIxRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:55 INFO - Running setup.py (path:/tmp/pip-35eOFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:55 INFO - Running setup.py (path:/tmp/pip-VvlSZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:55 INFO - Running setup.py (path:/tmp/pip-HnqhjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:55 INFO - Running setup.py (path:/tmp/pip-oHJ03c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:55 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)) 07:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:55 INFO - Running setup.py (path:/tmp/pip-Ri7DMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:55 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)) 07:37:55 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)) 07:37:55 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)) 07:37:55 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)) 07:37:55 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)) 07:37:55 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)) 07:37:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:55 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 07:37:55 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 07:37:55 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 07:37:55 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 07:37:55 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 07:37:55 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 07:37:57 INFO - Downloading blessings-1.6.tar.gz 07:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:37:57 INFO - Installing collected packages: blessings 07:37:57 INFO - Running setup.py install for blessings 07:37:57 INFO - Successfully installed blessings 07:37:57 INFO - Cleaning up... 07:37:57 INFO - Return code: 0 07:37:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:37:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:57 INFO - Reading from file tmpfile_stdout 07:37:57 INFO - Current package versions: 07:37:57 INFO - argparse == 1.2.1 07:37:57 INFO - blessings == 1.6 07:37:57 INFO - blobuploader == 1.2.4 07:37:57 INFO - docopt == 0.6.1 07:37:57 INFO - functools32 == 3.2.3-2 07:37:57 INFO - jsonschema == 2.5.1 07:37:57 INFO - manifestparser == 1.1 07:37:57 INFO - mozInstall == 1.12 07:37:57 INFO - mozcrash == 0.17 07:37:57 INFO - mozdebug == 0.1 07:37:57 INFO - mozdevice == 0.48 07:37:57 INFO - mozfile == 1.2 07:37:57 INFO - mozhttpd == 0.7 07:37:57 INFO - mozinfo == 0.9 07:37:57 INFO - mozleak == 0.1 07:37:57 INFO - mozlog == 3.3 07:37:57 INFO - moznetwork == 0.27 07:37:57 INFO - mozprocess == 0.23 07:37:57 INFO - mozprofile == 0.28 07:37:57 INFO - mozrunner == 6.12 07:37:57 INFO - mozscreenshot == 0.1 07:37:57 INFO - mozsystemmonitor == 0.3 07:37:57 INFO - moztest == 0.7 07:37:57 INFO - mozversion == 1.4 07:37:57 INFO - psutil == 3.1.1 07:37:57 INFO - requests == 1.2.3 07:37:57 INFO - wsgiref == 0.1.2 07:37:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:57 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:57 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 07:37:57 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 07:37:57 INFO - Using env: {'DISPLAY': ':0', 07:37:57 INFO - 'HOME': '/home/cltbld', 07:37:57 INFO - 'LANG': 'en_US.UTF-8', 07:37:57 INFO - 'LANGUAGE': 'en_US:en', 07:37:57 INFO - 'LOGNAME': 'cltbld', 07:37:57 INFO - 'MAIL': '/var/mail/cltbld', 07:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:57 INFO - 'MOZ_NO_REMOTE': '1', 07:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:57 INFO - 'NO_EM_RESTART': '1', 07:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:57 INFO - 'PWD': '/builds/slave/test', 07:37:57 INFO - 'SHELL': '/bin/bash', 07:37:57 INFO - 'SHLVL': '1', 07:37:57 INFO - 'TERM': 'linux', 07:37:57 INFO - 'TMOUT': '86400', 07:37:57 INFO - 'USER': 'cltbld', 07:37:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:57 INFO - '_': '/tools/buildbot/bin/python'} 07:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 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)) 07:37:58 INFO - Cleaning up... 07:37:58 INFO - Return code: 0 07:37:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:37:58 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')]} 07:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:58 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')]} 07:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:58 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 0x7f0f03e7cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', '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 07:37:58 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 07:37:58 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 07:37:58 INFO - Using env: {'DISPLAY': ':0', 07:37:58 INFO - 'HOME': '/home/cltbld', 07:37:58 INFO - 'LANG': 'en_US.UTF-8', 07:37:58 INFO - 'LANGUAGE': 'en_US:en', 07:37:58 INFO - 'LOGNAME': 'cltbld', 07:37:58 INFO - 'MAIL': '/var/mail/cltbld', 07:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:58 INFO - 'MOZ_NO_REMOTE': '1', 07:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:58 INFO - 'NO_EM_RESTART': '1', 07:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:58 INFO - 'PWD': '/builds/slave/test', 07:37:58 INFO - 'SHELL': '/bin/bash', 07:37:58 INFO - 'SHLVL': '1', 07:37:58 INFO - 'TERM': 'linux', 07:37:58 INFO - 'TMOUT': '86400', 07:37:58 INFO - 'USER': 'cltbld', 07:37:58 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:58 INFO - '_': '/tools/buildbot/bin/python'} 07:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:37:58 INFO - Cleaning up... 07:37:58 INFO - Return code: 0 07:37:58 INFO - Running post-action listener: _resource_record_post_action 07:37:58 INFO - Running post-action listener: _start_resource_monitoring 07:37:58 INFO - Starting resource monitoring. 07:37:58 INFO - [mozharness: 2016-08-31 14:37:58.300337Z] Finished create-virtualenv step (success) 07:37:58 INFO - [mozharness: 2016-08-31 14:37:58.300845Z] Running install step. 07:37:58 INFO - Running pre-action listener: _resource_record_pre_action 07:37:58 INFO - Running main action method: install 07:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:58 INFO - Reading from file tmpfile_stdout 07:37:58 INFO - Detecting whether we're running mozinstall >=1.0... 07:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:37:58 INFO - Reading from file tmpfile_stdout 07:37:58 INFO - Output received: 07:37:58 INFO - Usage: mozinstall [options] installer 07:37:58 INFO - Options: 07:37:58 INFO - -h, --help show this help message and exit 07:37:58 INFO - -d DEST, --destination=DEST 07:37:58 INFO - Directory to install application into. [default: 07:37:58 INFO - "/builds/slave/test"] 07:37:58 INFO - --app=APP Application being installed. [default: firefox] 07:37:58 INFO - mkdir: /builds/slave/test/build/application 07:37:58 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'] 07:37:58 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 07:38:14 INFO - Reading from file tmpfile_stdout 07:38:14 INFO - Output received: 07:38:14 INFO - /builds/slave/test/build/application/firefox/firefox 07:38:14 INFO - Running post-action listener: _resource_record_post_action 07:38:14 INFO - [mozharness: 2016-08-31 14:38:14.744932Z] Finished install step (success) 07:38:14 INFO - [mozharness: 2016-08-31 14:38:14.745216Z] Running run-tests step. 07:38:14 INFO - Running pre-action listener: _resource_record_pre_action 07:38:14 INFO - Running main action method: run_tests 07:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:38:14 INFO - Python 2.7.3 07:38:14 INFO - Return code: 0 07:38:14 INFO - grabbing minidump binary from tooltool 07:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:38:14 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 0x1ecf090>, '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 0x1ed29b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed2e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:38:14 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 07:38:14 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 07:38:14 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 07:38:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:38:14 INFO - Return code: 0 07:38:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:38:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:38:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:38:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:38:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 07:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 07:38:14 INFO - Using env: {'DISPLAY': ':0', 07:38:14 INFO - 'HOME': '/home/cltbld', 07:38:14 INFO - 'LANG': 'en_US.UTF-8', 07:38:14 INFO - 'LANGUAGE': 'en_US:en', 07:38:14 INFO - 'LOGNAME': 'cltbld', 07:38:14 INFO - 'MAIL': '/var/mail/cltbld', 07:38:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:38:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:14 INFO - 'MOZ_NO_REMOTE': '1', 07:38:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:38:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:14 INFO - 'NO_EM_RESTART': '1', 07:38:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:14 INFO - 'PWD': '/builds/slave/test', 07:38:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:38:14 INFO - 'SHELL': '/bin/bash', 07:38:14 INFO - 'SHLVL': '1', 07:38:14 INFO - 'TERM': 'linux', 07:38:14 INFO - 'TMOUT': '86400', 07:38:14 INFO - 'USER': 'cltbld', 07:38:14 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892', 07:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:14 INFO - '_': '/tools/buildbot/bin/python'} 07:38:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 07:38:15 INFO - mozversion application_buildid: 20160831061621 07:38:15 INFO - mozversion application_changeset: 3d9cabea1e561a62734fa8d4ce8005696ef33f6d 07:38:15 INFO - mozversion application_display_name: Nightly 07:38:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:38:15 INFO - mozversion application_name: Firefox 07:38:15 INFO - mozversion application_remotingname: firefox 07:38:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:38:15 INFO - mozversion application_vendor: Mozilla 07:38:15 INFO - mozversion application_version: 51.0a1 07:38:15 INFO - mozversion platform_buildid: 20160831061621 07:38:15 INFO - mozversion platform_changeset: 3d9cabea1e561a62734fa8d4ce8005696ef33f6d 07:38:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:38:15 INFO - mozversion platform_version: 51.0a1 07:38:15 INFO - using testdate: 1472654295 07:38:15 INFO - actual date: 1472654295 07:38:15 INFO - starting webserver on 'localhost:36415' 07:38:15 INFO - SUITE-START | Running 2 tests 07:38:15 INFO - TEST-START | dromaeo_css 07:38:15 INFO - operating with platform_type : linux_ 07:38:15 INFO - Initialising browser for dromaeo_css test... 07:38:15 INFO - TEST-INFO | started process 12392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeBePsd/profile http://localhost:36415/getInfo.html) 07:38:19 INFO - PROCESS | 12392 | __metrics Screen width/height:1600/1200 07:38:19 INFO - PROCESS | 12392 | colorDepth:24 07:38:19 INFO - PROCESS | 12392 | Browser inner width/height: 1024/697 07:38:19 INFO - PROCESS | 12392 | __metrics 07:38:20 INFO - PROCESS | 12392 | 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" 07:38:20 INFO - PROCESS | 12392 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:38:20 INFO - PROCESS | 12392 | console.error: PushService: 07:38:20 INFO - PROCESS | 12392 | stateChangeProcessEnqueue: Error transitioning state 07:38:20 INFO - PROCESS | 12392 | UnknownError 07:38:21 INFO - TEST-INFO | 12392: exit 0 07:38:21 INFO - Browser initialized. 07:38:21 INFO - Running cycle 1/1 for dromaeo_css test... 07:38:21 INFO - TEST-INFO | started process 12472 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeBePsd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:33 INFO - PROCESS | 12472 | RSS: Main: 182013952 07:38:33 INFO - PROCESS | 12472 | 07:39:24 INFO - PROCESS | 12472 | 1472654364789 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] 07:41:38 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36415/tests/dromaeo/cssquery-ext.html) 07:41:39 INFO - PROCESS | 12472 | RSS: Main: 274194432 07:41:39 INFO - PROCESS | 12472 | 07:44:41 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-ext.html (next: http://localhost:36415/tests/dromaeo/cssquery-jquery.html) 07:44:42 INFO - PROCESS | 12472 | RSS: Main: 271212544 07:44:42 INFO - PROCESS | 12472 | 07:47:44 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36415/tests/dromaeo/cssquery-mootools.html) 07:47:45 INFO - PROCESS | 12472 | RSS: Main: 260313088 07:47:45 INFO - PROCESS | 12472 | 07:50:47 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36415/tests/dromaeo/cssquery-prototype.html) 07:50:48 INFO - PROCESS | 12472 | RSS: Main: 219385856 07:50:48 INFO - PROCESS | 12472 | 07:53:51 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36415/tests/dromaeo/cssquery-yui.html) 07:53:52 INFO - PROCESS | 12472 | RSS: Main: 219807744 07:53:52 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | Cycle 1(1): loaded http://localhost:36415/tests/dromaeo/cssquery-yui.html (next: http://localhost:36415/tests/dromaeo/cssquery-dojo.html) 07:56:55 INFO - PROCESS | 12472 | RSS: Main: 306716672 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | __start_tp_report 07:56:55 INFO - PROCESS | 12472 | _x_x_mozilla_page_load 07:56:55 INFO - PROCESS | 12472 | _x_x_mozilla_page_load_details 07:56:55 INFO - PROCESS | 12472 | |i|pagename|runs| 07:56:55 INFO - PROCESS | 12472 | |0;dojo.html;2581.083715281075;2588.1976587257964;2601.414681696617;2611.490759301936;2625.1861922803932;4482;4572.977135114324;4593.379893714347;4634.606058485028;4707.9764601176985;4534.5465453454635;4564.42944631921;4595.8621241362725;4620.561046700558;4635.652326075541;4210.789460526977;4222.535521092677;4261.062566235423;4323.913521729563;4420.138073075752;4340.717853339539;4381.561843815609;4428.490723566782;4429.64562834974;4433.357163211349;4199.181159673864;4246.554111818276;4317.805698743564;4327.805248763813;4434.733915965053;4198.958010419918;4229.830806767725;4281.593248641374;4316.676249281285;4338.197433474786;4181.644560212355;4194.559571245006;4196.853110141161;4197.265478541243;4267.935980960288;4512.887177820581;4548.499665036877;4555.111753208126;4582.129395414894;4634.050019745944;4921.630877684188;4957.851264462072;4982.9252561211615;4984.202527595604;4986.950130498685;4995.425526064513;4998.050370429607;4998.700077995332;5018.04657115147;5074.543291103819;4575.062112266977;4594.540545945379;4682.999999999966;4683.882902927454;4686.531346865354;5007.098722230034;5073.315102461194;5087.618428617869;5154.278401023785;5159.329287192641;4874.46380898101;4883.658143929891;4890.046440944053;4893.314935908976;4958.107540642647;4823.27650852368;4909.410870695539;4926.556609905054;4935.8025678972435;4945.8021679132435;4910.116179087727;4957.7769000395065;4964.106460837084;4994.675346102565;5000.924986125211;4908.779104940286;4939;4950.381202349707;4951.925721114186;5012.348394708664;3927.7446965947124;3954.169624378855;4039.818208180639;4072.959270407258;4089.5501494835544;3588.551431071116;3606.7294952878638;3629.0564453242346;3665.8350374233223;3708.3325001499447;4390.626796722315;4393.978030109829;4395.340698895191;4418.64650827933;4449.555044495524;4887.584555312767;5048.066107770074;5058.721770302669;5059.316992206079;5061.392632884077;4278.935815962763;4285.721428107163;4308.74147551148;4318.071614602875;4380.014496738208;4430.601245887886;4431.7340959542535;4462.598366146997;4489.169503641837;4491.438570178728;4676.836310729108;4677.064587082665;4682.180618391836;4682.742449165328;4741.810327586858;4843.128236917248;4958.8760280993265;4959.380077490314;4962.057209130254;5014.548690617862;4729.5034021427255;4731.479537250969;4733.171694953438;4779.211430113992;4787.760611969457;4935.284383764406;4937.432195297587;4939.357883475125;4941.357623508921;4998.750062496933;4860.8298709545;4943.406791185081;5010.423801262901;5014.323066385991;5064.898702026013;4861.027794441196;4864.294677271847;4876.171050921423;4910.361652985089;4939.75301234944;4870.4155501340065;4872.561469467765;4872.780724867461;4874.683145595525;4925.113479573711;4648.907021859476;4777.617790576815;4808.326834243138;4818.036392721399;4870.537298956593;4896.510348965075;4923.310736496965;4923.729194894315;4969.4782047884455;4974.8756281093265;4817.0606731686985;4818.620235032825;4826.251930950708;4828.782704778364;4880.975595122144 07:56:55 INFO - PROCESS | 12472 | |1;ext.html;552.04771768699;556.0935674849994;559.5831105826159;570.2102587915737;574.6351067072408;27992.720072799268;37846.81076594617;39511.80244098779;39577;43448;39495.40756888649;39975.80012099935;40016;40092.59907400925;40111.197776044464;7612.010438642982;7796.1814009529035;7800.453968222227;7801.102873169573;7801.102873169573;7625.199354067826;7702.537847729154;7717;7721.459497835153;7724.497907636014;7697.076350837908;7780.766577002699;7792.376609871225;7796.688132474695;7803.29770320671;7720.07359116904;7765.339946104588;7774.416918731118;7775.105862825791;7783.104942931522;7656.9234307657325;7690.038745156856;7701.152873183945;7701.961490192569;7704.6532906019365;37539.62460375389;37578.81210593957;37600.62399375999;37964.05089872747;37980.81009594962;3102.395032968572;3119.485284927984;3121.641011283709;3253.9511907321407;3601.981990090033;28356.432871342662;28729.281767955967;28831.42337153245;28845.42309153805;30450.238744031354;3331.5002749587516;3332.450145725952;3349;3362.915927101842;3380.577427821522;254242;254402;255014;255048;255598;30796.69203307983;50636.493635063176;50897.23654145191;50932.49067509352;51019.23471147936;31217.68782312148;32962.3407531848;33005.17487062843;33018.33963320768;33085.83457082747;13409.463621455228;13611.659708507168;13616.863831361758;13617.319134043457;13621.455141794217;11050.723731906606;11210.327380357205;11241.775164496665;11246;11259.605913792979;7579.431542634215;7755.961220193975;7813.062432508022;7814;7816.57008864518;2983.612130423031;3012.141539661186;3018.7132222439272;3056.251218451449;3076.969230307668;828.6892415344246;880.8898887639045;919.3105171121658;921.2860033474111;940.0270719805105;26476.205713828615;26862.731372686023;27315.726842731714;27473;27768.58347124795;830.4477522447626;945.522511131874;961.6682244625594;1007.677543186188;1030.5362586835954;13670.794938075936;14644.341004654816;14839.319470699347;14862;14879.553613391616;367433;370702;371063;371461;371644.977277382;48157.75921120372;48615.756921215165;48624;48641.513584864406;48652.27021594679;1064.904158625712;1149.0807354116673;1170.8243763435382;1185.2118341302937;1196.36592605919;25233;27729.861350693925;28699.28251793722;28995.27511812137;29039.56440653434;41017.384739228335;41433.58566414418;41511.37732933948;41535;41567;281171;281734;281975;283045;283565;21416;21957.341279760993;22154.113835446402;22274.109035638394;22302.21942232014;40619.59380406158;40783;41135.1772964533;41136.38295425511;42834.35748463838;34684;34949;35003;35008.824955875054;35516.64483355237;34962;35039.47440788339;35159;35366.115847104025;35404.46893296551;34129.65870341265;34548.136296592784;34700;34924.12689682778;35126.47310290399;29565.408691826473;32061.35877282488;32146.839265803523;32235.35529289448;32654.673453265164;24599;24906.42973860312;24999.99999999974;25017.624735629342;25240.242792716246 07:56:55 INFO - PROCESS | 12472 | |2;jquery.html;343.8487065691094;370.0895796341001;370.51462584014945;375.2832090706749;387.52915208022245;11887.643370698872;15521.223938803058;15900.52298431049;17761.20074596643;17995.37016204433;16497.752533711973;16520.504384868473;16600.916995415006;16615.169241537937;16880;4909.11635905536;5035.169197082495;5045.29365888774;5062.417821950467;5086;3583.4803953426735;3727.254549090193;4857.635677324197;5057.671251368668;5058.190689489682;3765.171662234318;3769.7172712046568;5219.921701174486;5913.349531551547;5923.200367950315;5910.822675319704;5920.555958303123;5924.674142922137;5925.940740592582;5928.051511758141;5147.6911385317;5187.403448603383;5261.053010458116;5268.394134674523;5279.815206467755;19186;19733.21067157312;20007.299744508873;21294.148234070617;21476.140954361807;1826;1849.177116183299;2202.977970220293;2242.742084660252;2363.0902102690416;9730.708078757649;10014.198864090855;10839.945800270949;12979.935100324532;13642.52251171214;1744.1540852686437;1767.1517671517615;1781.7594624725625;1790.8746387752863;1794.120880768427;67804;73336;75164.99999999945;75550.24449755542;75990.24009759887;9554.331196816156;9575.23398128148;9851.064148905842;9857.162141217934;9997.500124993721;12756.553520626734;12772.89247274208;12789.23264604108;12823;12883.35583220854;7101.502894797314;7259.491835571459;7287.234840341794;7483.064616922885;7514.511556748794;6540.378664027005;6657.234418041987;6674.733010679615;6925.826854328581;7158.892616610755;4012.1574469361763;4060.7563546187325;4137.710360274752;4382.868513944587;4402.801873915681;1781.4922747017297;1787.204693911223;1800.7388928605542;1802.9368972085913;1897.6868816645374;820.5035953248318;845.4124383553371;856.4647095565272;877.5787621941377;885.9291256699448;16293.918530407509;16601.83398166027;17121.315147393965;17132.486025419257;17607.29570817153;808.720991258015;811.5090370325986;845.2688424512875;869.6390997735868;879.6393478673841;8385.832283354299;9671.274654400948;9728.75678108053;9770.706878793648;9895.356801807859;363870;366182;367022;368316;391601.0419947882;81154.59422702849;81666;81911.59044204741;83229;84332.21874016062;876.8772371867943;888.3071204460531;889.4263200235812;890.2655309369795;890.8708237305684;14416.855831441972;15313.693726125191;15384.38462461489;15464.768028479357;16254.674906501567;38666;38861;39350.2129957405;39494.60505394909;39989.600103998586;52368.73815630897;52631.47368526266;53107.20339194838;55002;56465;7749.147593764568;7758.7672369829;7829.45193836426;7879.448438609243;8187.590620469072;9113.3165012623;11787.528498859949;11828.940855295668;11850.881491184979;12057.698557536132;10674.306170098918;10742.24804263694;10805.51375188134;10856.10637245339;10950.06924411434;10074.042965918226;10143.137833283958;10443.216758743276;10464.476776161313;10536.947315263375;9621.711348659552;9861.506924653595;9964.10323070927;9993.700188994342;10026.047525485068;7663.693452261847;7683.807904802201;7780.688772449265;7826.5695386751995;7895.368370530229;9942.751431214278;10162.54268557902;10188.286819922534;10274.691759247235;10776.353418794897 07:56:55 INFO - PROCESS | 12472 | |3;mootools.html;1218.4151607228537;1326.1777697827345;1363.40010395426;1418.524794193945;1670.7995040595122;6987.860242795141;7232.0959880015;7302.306280903319;7515.774526764192;7543.811404714885;7342.522736022168;7431.405487560995;7442.553446793183;7452.776416707507;7456.179820219785;2178.58606864696;2193.835462340323;2208.2602328219987;2225.944351391212;2234.5866014787234;2129.957400851982;2167.5014746608213;2199.813015893643;2206.2719302630094;2219.9556008879813;2171.2834764527747;2175.6845257437662;2181.8472706910525;2196.4508872781803;2211.5403833469913;2141.3468891987936;2162.145952348821;2168.035224325176;2173.0981642618294;2177.923772667965;2100.5693832764246;2133.200049981257;2138.0485683870684;2142.2716276466076;2164.3723320237114;7402.518836275625;7474.289942455458;7510;7510.211427800112;7539;4499.235130027903;4694.413198350207;4702.694324868907;4705.71765694056;4708;2860.0990687933236;2959.733623973853;3006.744426723731;3017.0345489443166;3020.7130322619314;987.8962708915604;993.7764003099317;1009.1775203209361;1010.1968934696934;1011.1708399112731;10388.688339349832;10444.582216711322;10455.268131230741;10470.581176752921;10475.685729428053;4032.8790136295956;4262.850800221977;4415.205263052617;4427.358033085185;4428.955710442854;8207.425480216447;8288.668453261922;8334.416590838584;8349.54077525742;8359.665613375519;4598.517155698671;4607.723536587749;4616.792244349012;4618.35343051973;4619.260918253063;3584.121890136933;4170.103427763045;4233.343831706091;4286.442762440863;4302.526722060571;3535.41665625169;3603.747737658339;3739.7569158004644;3745.9063523412133;3765.171662234304;1186.1578279421535;1216.1243904388825;1217.6468824041106;1229.9631011069503;1236.1717649175075;799.5442597719244;805.7824387415365;807.5518087461397;819.9016118065871;825.7275099217244;4982.07831551157;5079.644424890296;5120.948790512121;5128.9230661540105;5159;413.78069623099805;423.47489113499654;428.5093567864777;431.2883741826012;435.1775144975998;3473.6179020307745;3538.292341531704;3570.9285814283567;3591.40741777609;3637.8726744563814;9162.083791620784;9390.389624674373;9469.053094690476;9479.715608531755;9486.051394860458;8718.782030449289;9143.817123657489;9397.107274808883;9403.57683904226;9416.952915235517;6990.790276291617;7193.3166349196745;7209.747658831916;7210.567365958059;7216.278372162847;5123.590112791042;5317.7341132943975;5372.086745253238;5372.113601255749;5375.919361209507;8597.742067737978;8641.796341932766;8686.609102590272;8772.956135219283;8775.166359195866;8776.868346974928;8802.163794439519;8949.32880033987;9004.729858104267;9052.094790520896;5847.883042339045;6384.138141350858;6520.836979075561;6606.207255129415;6793.796186114425;7521.7743467696055;7576.62116894142;7589.468737188565;7597.164311925794;7598.772036838904;6623.437007854193;6625.900611490735;6755.966220168868;6794.320567943166;6797.286284940208;6532.706028228836;6607.108040414675;6634.83412914681;6815.659217039228;6847.691853866489;6227.719752611234;6248.937510625017;6296;6318.241810982711;6321.304656487875;4463.933041004322;4491.079328737666;4496.572825581565;4625.352450656844;4647.047355292225;4285.828566857291;4327.459067616548;4427.380166776589;4509.1658043262105;4657.510961349009 07:56:55 INFO - PROCESS | 12472 | |4;prototype.html;287.55141978513507;295.30308472006067;301.31150321515327;302.30017509465574;302.4827544898225;4747.715137091773;4766.7616619169075;4795.856124316268;4797.136515427222;4799.25611530213;4669.696469729465;4689.507601701824;4733.786979585919;4737.478877323491;4739.834105806298;3762.8523300393367;3776.150366167611;3786.7692999775045;3800.6579407853287;3816.5420149582087;3463.904337875587;3803.220339830328;3806.3719486284867;3812.485314482551;3822.2737679840743;3666.3217304798563;3675.191457879262;3695.353313170198;3697.9445308320264;3699.9445008324897;3664.5419322584676;3673.559172899256;3675.9632403675887;3693.2059607184583;3704.833282502294;3040.8783648654025;3118.5945827042565;3187.6493585705784;3263.4288999425007;3276.3611095836322;4549.7042692224895;4551.5676010778925;4564.931526027112;4589.747563883985;4607.401037865056;4143.736160471055;4152.211079894811;4152.896177595584;4154.792260386968;4157.625813676784;4603.332516785047;4703;4872.853814385574;4928.704277743311;4942.481039490874;3465.7920524768597;3485.5294535237927;3489.4416893296952;3495.667911548399;3517.6306487818924;5081.720505372203;5122.308488354099;5134.204198349262;5136.357955255593;5140.280360749498;3392.9491057634154;3600.3879340512176;3604.9639503604626;3606.124815096188;3608.3685355063285;3574.696150827157;3599.884035948864;3606.711463082947;3642.5993140754495;3644.5444319460066;4150.522689890701;4158.500979882433;4160.625543701081;4188.141431006637;4191.476065491814;4865.635077369211;4880.951190488121;4882.12121818069;4906.871419573518;4928.975355123273;1237.5792230641446;1259.785836407813;1262.419287127913;1270.9046821488425;1272.5100836178137;793.6269953122023;802.7110240313483;820.9876851847227;822.8189798244376;822.9753107406788;4024;4024.464587309976;4040.2929487340184;4043.757374557536;4045.1505183911117;4475.024108967566;4507.594316511464;4515.532451953129;4547.931781023287;4578.816847326137;3965.72239943207;3971.980140099281;3972;3977.781222032816;3999.34010888206;2320.581476769527;2359.209664762324;2395.6885604871604;2403.1108489858866;2415.3237093613816;4285.635720963753;4300.569943005675;4566.49768525462;4683.367745354333;4807.903841923142;5183.663061900965;5193.169092945186;5199.298094757235;5201;5241.344831896013;4811.615070794398;4825.445073816486;4838.298446725275;4850.089946032423;4855;4871.780769865435;5025.497450254945;5027.673201241908;5037.647364684583;5087.651772280275;4926.891449423851;4932.21084626458;4935.272654570871;4944.332515110414;4947.975260123676;4770.403699537558;4789.61683065347;4798.7840547174765;4806.7836947336755;4809.855704328876;3410.0281419795238;3529.9939517237462;3562.2697347043277;3575.12409459684;3613.331533666175;5024.070546948826;5034.496550344937;5038.47096054909;5051.267566202953;5063.3417655704525;4921.926171107365;4940.827071052496;4944;4952.182889823139;4953.356063711694;4728.479867214535;4873.585745211697;4923.335349727884;4954.851354459372;4955.40535135786;4817.253325734517;4824.469308376146;4824.8793780155775;4834.984653222863;4861;4908.558229759339;4959.752012399438;4981.8007279709855;4982.604870636228;4994.1260279450225;4625.722456652477;4796.136695394863;4816.34979277793;4882.780274887567;4883.975580122076 07:56:55 INFO - PROCESS | 12472 | |5;yui.html;639.8400399900025;665.6538599928037;666.7999600119964;700.9555761914746;734.7391675955035;13206.075574709774;14348.713025739478;14371.209583472904;14390.928045359758;14408.423663053478;13438.19370837748;13557;13576.253306068162;13587.252701101435;13629.386677599507;612.6446660936668;736.3556887723229;746.7908985484073;766.6588368176164;768.5657603454058;768.0322793280479;772.1082150116624;773.0105465004775;774.094309657699;774.2412435812906;809.1625167951172;810.2505182706003;810.9229623185788;810.9351251899864;811.2941740685612;636.4017823246157;649.5128653509868;650.5023656902473;654.9148610680629;812.5368539932241;573.160320131006;586.1676419484343;588.1794896119932;590.8493334199752;591.5326891755508;12781.61655150347;12813.615591532269;12818.359082045861;12859.678508037281;12866.614001579968;11987.820182697267;12420.503179872885;12753;13181.60455186346;13285.86714132856;11527.365994870279;11962.940185299105;12269.509219631203;12381.938090309492;12754.426050827735;4089.754614723126;4126.546079931205;4296.806643701041;4318.784060796947;4338.978305108486;65187;65237.02144467789;65332;65502;65620;9462.337636365388;9520.761980950394;9520.809898762654;10230.59077636901;10634.840477392845;17666.646667066587;17858.732119018227;18045.909770451064;18111.72832407515;18397.54006149857;6889.379955804002;7001.264867188974;7017.964910175417;7042.330978557029;7234.6382680865745;3611.530501034849;3624.7100231981376;3706.5181526401393;3725.627437256307;3767.811609419518;2858.957115643267;2935.6770755216908;2941.4558306713325;2949.1742312152633;2952.099609619087;504.2109099259647;529.4731741916818;538.5906710899744;561.7724821447321;586.8386193796655;496.9602431805447;507.52546369144966;536.2974503400558;579.2932622200908;581.2443823030044;7659.4255430841795;7890.802729931683;7954.284114429729;8286.295664868554;8609.655613775503;485.26482379195545;500.4320096690283;553.4244385838763;556.9164625306155;576.1127863090793;3619.1856832212543;3901.2197560488016;3910.570075983569;4032.1532478179324;4230.513490948519;85046.02338918108;93200.53399732958;93224.53387733018;95114.52442737742;95170;83452;84050.57974710087;84594.5770271157;84994.15005849862;85673;75331;75577;76146.85779713308;76181.61909190418;76364.61817690988;7459.328660420588;7576.211894052974;7995.1605081465605;8001.279884810395;8240.13478584764;41451;42895.9276018102;43950;44305.55694443014;44808.327875081246;56799.43200567942;65511.672441637485;65934.34065659282;65953.34046659472;66118.33881661315;9485.95654477993;9520.61917523319;9534.284928630243;9583.712488625353;9701.417914925127;10467.057964783206;10472.633457829244;10562.366258024544;11268.605598804003;11368.147388946027;10875.456227188768;11063.778724425627;11408.258463199865;11578.768424631291;11589.594364197214;9511.61953521879;9655.758606034906;9932.056454636799;10379.11777498921;10383.584656613652;10258.333208341433;10577.524011419659;10689.251752377259;11037.558497660324;11079.612213572485;8827.16141966512;8888.955555222183;9075.500847453453;9307.813843723223;9314.813703726024;9085.818283634422;9442.10300021497;9508.239340852853;9549.52252387364;9721.902780972383 07:56:55 INFO - PROCESS | 12472 | __end_tp_report 07:56:55 INFO - PROCESS | 12472 | __start_cc_report 07:56:55 INFO - PROCESS | 12472 | _x_x_mozilla_cycle_collect,843 07:56:55 INFO - PROCESS | 12472 | __end_cc_report 07:56:55 INFO - PROCESS | 12472 | __startTimestamp1472655415613__endTimestamp 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | ------- Summary: start ------- 07:56:55 INFO - PROCESS | 12472 | Number of tests: 6 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#0] dojo.html Cycles:165 Average:4599.98 Median:4733.17 stddev:491.18 (10.4%) stddev-sans-first:466.45 07:56:55 INFO - PROCESS | 12472 | Values: 2581.1 2588.2 2601.4 2611.5 2625.2 4482.0 4573.0 4593.4 4634.6 4708.0 4534.5 4564.4 4595.9 4620.6 4635.7 4210.8 4222.5 4261.1 4323.9 4420.1 4340.7 4381.6 4428.5 4429.6 4433.4 4199.2 4246.6 4317.8 4327.8 4434.7 4199.0 4229.8 4281.6 4316.7 4338.2 4181.6 4194.6 4196.9 4197.3 4267.9 4512.9 4548.5 4555.1 4582.1 4634.1 4921.6 4957.9 4982.9 4984.2 4987.0 4995.4 4998.1 4998.7 5018.0 5074.5 4575.1 4594.5 4683.0 4683.9 4686.5 5007.1 5073.3 5087.6 5154.3 5159.3 4874.5 4883.7 4890.0 4893.3 4958.1 4823.3 4909.4 4926.6 4935.8 4945.8 4910.1 4957.8 4964.1 4994.7 5000.9 4908.8 4939.0 4950.4 4951.9 5012.3 3927.7 3954.2 4039.8 4073.0 4089.6 3588.6 3606.7 3629.1 3665.8 3708.3 4390.6 4394.0 4395.3 4418.6 4449.6 4887.6 5048.1 5058.7 5059.3 5061.4 4278.9 4285.7 4308.7 4318.1 4380.0 4430.6 4431.7 4462.6 4489.2 4491.4 4676.8 4677.1 4682.2 4682.7 4741.8 4843.1 4958.9 4959.4 4962.1 5014.5 4729.5 4731.5 4733.2 4779.2 4787.8 4935.3 4937.4 4939.4 4941.4 4998.8 4860.8 4943.4 5010.4 5014.3 5064.9 4861.0 4864.3 4876.2 4910.4 4939.8 4870.4 4872.6 4872.8 4874.7 4925.1 4648.9 4777.6 4808.3 4818.0 4870.5 4896.5 4923.3 4923.7 4969.5 4974.9 4817.1 4818.6 4826.3 4828.8 4881.0 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#1] ext.html Cycles:180 Average:44458.20 Median:35005.91 stddev:80738.90 (230.6%) stddev-sans-first:80898.09 07:56:55 INFO - PROCESS | 12472 | Values: 552.0 556.1 559.6 570.2 574.6 27992.7 37846.8 39511.8 39577.0 43448.0 39495.4 39975.8 40016.0 40092.6 40111.2 7612.0 7796.2 7800.5 7801.1 7801.1 7625.2 7702.5 7717.0 7721.5 7724.5 7697.1 7780.8 7792.4 7796.7 7803.3 7720.1 7765.3 7774.4 7775.1 7783.1 7656.9 7690.0 7701.2 7702.0 7704.7 37539.6 37578.8 37600.6 37964.1 37980.8 3102.4 3119.5 3121.6 3254.0 3602.0 28356.4 28729.3 28831.4 28845.4 30450.2 3331.5 3332.5 3349.0 3362.9 3380.6 254242.0 254402.0 255014.0 255048.0 255598.0 30796.7 50636.5 50897.2 50932.5 51019.2 31217.7 32962.3 33005.2 33018.3 33085.8 13409.5 13611.7 13616.9 13617.3 13621.5 11050.7 11210.3 11241.8 11246.0 11259.6 7579.4 7756.0 7813.1 7814.0 7816.6 2983.6 3012.1 3018.7 3056.3 3077.0 828.7 880.9 919.3 921.3 940.0 26476.2 26862.7 27315.7 27473.0 27768.6 830.4 945.5 961.7 1007.7 1030.5 13670.8 14644.3 14839.3 14862.0 14879.6 367433.0 370702.0 371063.0 371461.0 371645.0 48157.8 48615.8 48624.0 48641.5 48652.3 1064.9 1149.1 1170.8 1185.2 1196.4 25233.0 27729.9 28699.3 28995.3 29039.6 41017.4 41433.6 41511.4 41535.0 41567.0 281171.0 281734.0 281975.0 283045.0 283565.0 21416.0 21957.3 22154.1 22274.1 22302.2 40619.6 40783.0 41135.2 41136.4 42834.4 34684.0 34949.0 35003.0 35008.8 35516.6 34962.0 35039.5 35159.0 35366.1 35404.5 34129.7 34548.1 34700.0 34924.1 35126.5 29565.4 32061.4 32146.8 32235.4 32654.7 24599.0 24906.4 25000.0 25017.6 25240.2 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#2] jquery.html Cycles:180 Average:24166.84 Median:4392.84 stddev:61767.62 (1406.1%) stddev-sans-first:61915.00 07:56:55 INFO - PROCESS | 12472 | Values: 343.8 370.1 370.5 375.3 387.5 11887.6 15521.2 15900.5 17761.2 17995.4 16497.8 16520.5 16600.9 16615.2 16880.0 4909.1 5035.2 5045.3 5062.4 5086.0 3583.5 3727.3 4857.6 5057.7 5058.2 3765.2 3769.7 5219.9 5913.3 5923.2 5910.8 5920.6 5924.7 5925.9 5928.1 5147.7 5187.4 5261.1 5268.4 5279.8 19186.0 19733.2 20007.3 21294.1 21476.1 1826.0 1849.2 2203.0 2242.7 2363.1 9730.7 10014.2 10839.9 12979.9 13642.5 1744.2 1767.2 1781.8 1790.9 1794.1 67804.0 73336.0 75165.0 75550.2 75990.2 9554.3 9575.2 9851.1 9857.2 9997.5 12756.6 12772.9 12789.2 12823.0 12883.4 7101.5 7259.5 7287.2 7483.1 7514.5 6540.4 6657.2 6674.7 6925.8 7158.9 4012.2 4060.8 4137.7 4382.9 4402.8 1781.5 1787.2 1800.7 1802.9 1897.7 820.5 845.4 856.5 877.6 885.9 16293.9 16601.8 17121.3 17132.5 17607.3 808.7 811.5 845.3 869.6 879.6 8385.8 9671.3 9728.8 9770.7 9895.4 363870.0 366182.0 367022.0 368316.0 391601.0 81154.6 81666.0 81911.6 83229.0 84332.2 876.9 888.3 889.4 890.3 890.9 14416.9 15313.7 15384.4 15464.8 16254.7 38666.0 38861.0 39350.2 39494.6 39989.6 52368.7 52631.5 53107.2 55002.0 56465.0 7749.1 7758.8 7829.5 7879.4 8187.6 9113.3 11787.5 11828.9 11850.9 12057.7 10674.3 10742.2 10805.5 10856.1 10950.1 10074.0 10143.1 10443.2 10464.5 10536.9 9621.7 9861.5 9964.1 9993.7 10026.0 7663.7 7683.8 7780.7 7826.6 7895.4 9942.8 10162.5 10188.3 10274.7 10776.4 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#3] mootools.html Cycles:180 Average:5031.01 Median:4704.21 stddev:2781.92 (59.1%) stddev-sans-first:2774.97 07:56:55 INFO - PROCESS | 12472 | Values: 1218.4 1326.2 1363.4 1418.5 1670.8 6987.9 7232.1 7302.3 7515.8 7543.8 7342.5 7431.4 7442.6 7452.8 7456.2 2178.6 2193.8 2208.3 2225.9 2234.6 2130.0 2167.5 2199.8 2206.3 2220.0 2171.3 2175.7 2181.8 2196.5 2211.5 2141.3 2162.1 2168.0 2173.1 2177.9 2100.6 2133.2 2138.0 2142.3 2164.4 7402.5 7474.3 7510.0 7510.2 7539.0 4499.2 4694.4 4702.7 4705.7 4708.0 2860.1 2959.7 3006.7 3017.0 3020.7 987.9 993.8 1009.2 1010.2 1011.2 10388.7 10444.6 10455.3 10470.6 10475.7 4032.9 4262.9 4415.2 4427.4 4429.0 8207.4 8288.7 8334.4 8349.5 8359.7 4598.5 4607.7 4616.8 4618.4 4619.3 3584.1 4170.1 4233.3 4286.4 4302.5 3535.4 3603.7 3739.8 3745.9 3765.2 1186.2 1216.1 1217.6 1230.0 1236.2 799.5 805.8 807.6 819.9 825.7 4982.1 5079.6 5120.9 5128.9 5159.0 413.8 423.5 428.5 431.3 435.2 3473.6 3538.3 3570.9 3591.4 3637.9 9162.1 9390.4 9469.1 9479.7 9486.1 8718.8 9143.8 9397.1 9403.6 9417.0 6990.8 7193.3 7209.7 7210.6 7216.3 5123.6 5317.7 5372.1 5372.1 5375.9 8597.7 8641.8 8686.6 8773.0 8775.2 8776.9 8802.2 8949.3 9004.7 9052.1 5847.9 6384.1 6520.8 6606.2 6793.8 7521.8 7576.6 7589.5 7597.2 7598.8 6623.4 6625.9 6756.0 6794.3 6797.3 6532.7 6607.1 6634.8 6815.7 6847.7 6227.7 6248.9 6296.0 6318.2 6321.3 4463.9 4491.1 4496.6 4625.4 4647.0 4285.8 4327.5 4427.4 4509.2 4657.5 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#4] prototype.html Cycles:180 Average:4043.55 Median:4584.28 stddev:1185.82 (25.9%) stddev-sans-first:1155.15 07:56:55 INFO - PROCESS | 12472 | Values: 287.6 295.3 301.3 302.3 302.5 4747.7 4766.8 4795.9 4797.1 4799.3 4669.7 4689.5 4733.8 4737.5 4739.8 3762.9 3776.2 3786.8 3800.7 3816.5 3463.9 3803.2 3806.4 3812.5 3822.3 3666.3 3675.2 3695.4 3697.9 3699.9 3664.5 3673.6 3676.0 3693.2 3704.8 3040.9 3118.6 3187.6 3263.4 3276.4 4549.7 4551.6 4564.9 4589.7 4607.4 4143.7 4152.2 4152.9 4154.8 4157.6 4603.3 4703.0 4872.9 4928.7 4942.5 3465.8 3485.5 3489.4 3495.7 3517.6 5081.7 5122.3 5134.2 5136.4 5140.3 3392.9 3600.4 3605.0 3606.1 3608.4 3574.7 3599.9 3606.7 3642.6 3644.5 4150.5 4158.5 4160.6 4188.1 4191.5 4865.6 4881.0 4882.1 4906.9 4929.0 1237.6 1259.8 1262.4 1270.9 1272.5 793.6 802.7 821.0 822.8 823.0 4024.0 4024.5 4040.3 4043.8 4045.2 4475.0 4507.6 4515.5 4547.9 4578.8 3965.7 3972.0 3972.0 3977.8 3999.3 2320.6 2359.2 2395.7 2403.1 2415.3 4285.6 4300.6 4566.5 4683.4 4807.9 5183.7 5193.2 5199.3 5201.0 5241.3 4811.6 4825.4 4838.3 4850.1 4855.0 4871.8 5025.5 5027.7 5037.6 5087.7 4926.9 4932.2 4935.3 4944.3 4948.0 4770.4 4789.6 4798.8 4806.8 4809.9 3410.0 3530.0 3562.3 3575.1 3613.3 5024.1 5034.5 5038.5 5051.3 5063.3 4921.9 4940.8 4944.0 4952.2 4953.4 4728.5 4873.6 4923.3 4954.9 4955.4 4817.3 4824.5 4824.9 4835.0 4861.0 4908.6 4959.8 4981.8 4982.6 4994.1 4625.7 4796.1 4816.3 4882.8 4884.0 07:56:55 INFO - PROCESS | 12472 | 07:56:55 INFO - PROCESS | 12472 | [#5] yui.html Cycles:180 Average:17601.33 Median:586.50 stddev:25252.19 (4305.6%) stddev-sans-first:25290.91 07:56:55 INFO - PROCESS | 12472 | Values: 639.8 665.7 666.8 701.0 734.7 13206.1 14348.7 14371.2 14390.9 14408.4 13438.2 13557.0 13576.3 13587.3 13629.4 612.6 736.4 746.8 766.7 768.6 768.0 772.1 773.0 774.1 774.2 809.2 810.3 810.9 810.9 811.3 636.4 649.5 650.5 654.9 812.5 573.2 586.2 588.2 590.8 591.5 12781.6 12813.6 12818.4 12859.7 12866.6 11987.8 12420.5 12753.0 13181.6 13285.9 11527.4 11962.9 12269.5 12381.9 12754.4 4089.8 4126.5 4296.8 4318.8 4339.0 65187.0 65237.0 65332.0 65502.0 65620.0 9462.3 9520.8 9520.8 10230.6 10634.8 17666.6 17858.7 18045.9 18111.7 18397.5 6889.4 7001.3 7018.0 7042.3 7234.6 3611.5 3624.7 3706.5 3725.6 3767.8 2859.0 2935.7 2941.5 2949.2 2952.1 504.2 529.5 538.6 561.8 586.8 497.0 507.5 536.3 579.3 581.2 7659.4 7890.8 7954.3 8286.3 8609.7 485.3 500.4 553.4 556.9 576.1 3619.2 3901.2 3910.6 4032.2 4230.5 85046.0 93200.5 93224.5 95114.5 95170.0 83452.0 84050.6 84594.6 84994.2 85673.0 75331.0 75577.0 76146.9 76181.6 76364.6 7459.3 7576.2 7995.2 8001.3 8240.1 41451.0 42895.9 43950.0 44305.6 44808.3 56799.4 65511.7 65934.3 65953.3 66118.3 9486.0 9520.6 9534.3 9583.7 9701.4 10467.1 10472.6 10562.4 11268.6 11368.1 10875.5 11063.8 11408.3 11578.8 11589.6 9511.6 9655.8 9932.1 10379.1 10383.6 10258.3 10577.5 10689.3 11037.6 11079.6 8827.2 8889.0 9075.5 9307.8 9314.8 9085.8 9442.1 9508.2 9549.5 9721.9 07:56:55 INFO - PROCESS | 12472 | -------- Summary: end -------- 07:56:55 INFO - PROCESS | 12472 | 07:56:56 INFO - TEST-INFO | 12472: exit 0 07:56:56 INFO - TEST-OK | dromaeo_css | took 1121619ms 07:56:56 INFO - TEST-START | kraken 07:56:56 INFO - operating with platform_type : linux_ 07:56:56 INFO - Initialising browser for kraken test... 07:56:56 INFO - TEST-INFO | started process 12774 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQVd3sc/profile http://localhost:36415/getInfo.html) 07:57:01 INFO - PROCESS | 12774 | __metrics Screen width/height:1600/1200 07:57:01 INFO - PROCESS | 12774 | colorDepth:24 07:57:01 INFO - PROCESS | 12774 | Browser inner width/height: 1024/697 07:57:01 INFO - PROCESS | 12774 | __metrics 07:57:02 INFO - PROCESS | 12774 | 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" 07:57:02 INFO - PROCESS | 12774 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:57:02 INFO - PROCESS | 12774 | console.error: PushService: 07:57:02 INFO - PROCESS | 12774 | stateChangeProcessEnqueue: Error transitioning state 07:57:02 INFO - PROCESS | 12774 | UnknownError 07:57:03 INFO - TEST-INFO | 12774: exit 0 07:57:03 INFO - Browser initialized. 07:57:03 INFO - Running cycle 1/1 for kraken test... 07:57:03 INFO - TEST-INFO | started process 12856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQVd3sc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:57:15 INFO - PROCESS | 12856 | RSS: Main: 181936128 07:57:15 INFO - PROCESS | 12856 | 07:57:18 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36415/tests/kraken/driver.html?testName=audio-beat-detection) 07:57:19 INFO - PROCESS | 12856 | RSS: Main: 243437568 07:57:19 INFO - PROCESS | 12856 | 07:57:22 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36415/tests/kraken/driver.html?testName=audio-dft) 07:57:22 INFO - PROCESS | 12856 | RSS: Main: 225816576 07:57:22 INFO - PROCESS | 12856 | 07:57:25 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36415/tests/kraken/driver.html?testName=audio-fft) 07:57:26 INFO - PROCESS | 12856 | RSS: Main: 303820800 07:57:26 INFO - PROCESS | 12856 | 07:57:28 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36415/tests/kraken/driver.html?testName=audio-oscillator) 07:57:29 INFO - PROCESS | 12856 | RSS: Main: 213016576 07:57:29 INFO - PROCESS | 12856 | 07:57:31 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36415/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:57:32 INFO - PROCESS | 12856 | RSS: Main: 215121920 07:57:32 INFO - PROCESS | 12856 | 07:57:35 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36415/tests/kraken/driver.html?testName=imaging-darkroom) 07:57:35 INFO - PROCESS | 12856 | RSS: Main: 253853696 07:57:35 INFO - PROCESS | 12856 | 07:57:39 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36415/tests/kraken/driver.html?testName=imaging-desaturate) 07:57:39 INFO - PROCESS | 12856 | RSS: Main: 247422976 07:57:39 INFO - PROCESS | 12856 | 07:57:42 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36415/tests/kraken/driver.html?testName=json-parse-financial) 07:57:43 INFO - PROCESS | 12856 | RSS: Main: 250097664 07:57:43 INFO - PROCESS | 12856 | 07:57:45 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36415/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:57:45 INFO - PROCESS | 12856 | RSS: Main: 213987328 07:57:45 INFO - PROCESS | 12856 | 07:57:47 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:57:48 INFO - PROCESS | 12856 | RSS: Main: 220971008 07:57:48 INFO - PROCESS | 12856 | 07:57:50 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:57:51 INFO - PROCESS | 12856 | RSS: Main: 222818304 07:57:51 INFO - PROCESS | 12856 | 07:57:54 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:57:54 INFO - PROCESS | 12856 | RSS: Main: 224141312 07:57:54 INFO - PROCESS | 12856 | 07:57:57 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:57:58 INFO - PROCESS | 12856 | RSS: Main: 221626368 07:57:58 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | Cycle 1(1): loaded http://localhost:36415/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36415/tests/kraken/driver.html?testName=ai-astar) 07:58:00 INFO - PROCESS | 12856 | RSS: Main: 272617472 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | __start_tp_report 07:58:00 INFO - PROCESS | 12856 | _x_x_mozilla_page_load 07:58:00 INFO - PROCESS | 12856 | _x_x_mozilla_page_load_details 07:58:00 INFO - PROCESS | 12856 | |i|pagename|runs| 07:58:00 INFO - PROCESS | 12856 | |0;ai-astar;114;116;136;115;113;115;114;113;116;113 07:58:00 INFO - PROCESS | 12856 | |1;audio-beat-detection;160;126;152;187;124;159;152;128;178;127 07:58:00 INFO - PROCESS | 12856 | |2;audio-dft;160;161;163;161;162;161;162;162;163;163 07:58:00 INFO - PROCESS | 12856 | |3;audio-fft;76;75;75;165;76;77;75;77;157;76 07:58:00 INFO - PROCESS | 12856 | |4;audio-oscillator;83;86;151;84;83;84;147;83;83;143 07:58:00 INFO - PROCESS | 12856 | |5;imaging-gaussian-blur;117;117;117;117;116;116;116;117;116;117 07:58:00 INFO - PROCESS | 12856 | |6;imaging-darkroom;147;147;147;146;147;146;146;147;147;146 07:58:00 INFO - PROCESS | 12856 | |7;imaging-desaturate;95;95;96;95;95;95;96;95;95;94 07:58:00 INFO - PROCESS | 12856 | |8;json-parse-financial;64;66;66;65;67;66;66;67;66;66 07:58:00 INFO - PROCESS | 12856 | |9;json-stringify-tinderbox;65;66;67;65;66;66;66;66;67;66 07:58:00 INFO - PROCESS | 12856 | |10;stanford-crypto-aes;81;82;81;88;92;81;91;82;82;81 07:58:00 INFO - PROCESS | 12856 | |11;stanford-crypto-ccm;143;144;142;148;131;133;133;132;171;131 07:58:00 INFO - PROCESS | 12856 | |12;stanford-crypto-pbkdf2;174;179;180;173;174;172;173;172;175;173 07:58:00 INFO - PROCESS | 12856 | |13;stanford-crypto-sha256-iterative;70;74;70;71;68;69;69;68;68;68 07:58:00 INFO - PROCESS | 12856 | __end_tp_report 07:58:00 INFO - PROCESS | 12856 | __start_cc_report 07:58:00 INFO - PROCESS | 12856 | _x_x_mozilla_cycle_collect,2006 07:58:00 INFO - PROCESS | 12856 | __end_cc_report 07:58:00 INFO - PROCESS | 12856 | __startTimestamp1472655480274__endTimestamp 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | ------- Summary: start ------- 07:58:00 INFO - PROCESS | 12856 | Number of tests: 14 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#0] ai-astar Cycles:10 Average:116.50 Median:115.00 stddev:6.95 (6.0%) stddev-sans-first:7.31 07:58:00 INFO - PROCESS | 12856 | Values: 114.0 116.0 136.0 115.0 113.0 115.0 114.0 113.0 116.0 113.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:155.50 stddev:22.61 (14.5%) stddev-sans-first:23.65 07:58:00 INFO - PROCESS | 12856 | Values: 160.0 126.0 152.0 187.0 124.0 159.0 152.0 128.0 178.0 127.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:0.87 07:58:00 INFO - PROCESS | 12856 | Values: 160.0 161.0 163.0 161.0 162.0 161.0 162.0 162.0 163.0 163.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#3] audio-fft Cycles:10 Average:92.90 Median:76.00 stddev:35.95 (47.3%) stddev-sans-first:37.61 07:58:00 INFO - PROCESS | 12856 | Values: 76.0 75.0 75.0 165.0 76.0 77.0 75.0 77.0 157.0 76.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.00 stddev:30.64 (36.9%) stddev-sans-first:31.66 07:58:00 INFO - PROCESS | 12856 | Values: 83.0 86.0 151.0 84.0 83.0 84.0 147.0 83.0 83.0 143.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:58:00 INFO - PROCESS | 12856 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 117.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:58:00 INFO - PROCESS | 12856 | Values: 147.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:58:00 INFO - PROCESS | 12856 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.60 07:58:00 INFO - PROCESS | 12856 | Values: 64.0 66.0 66.0 65.0 67.0 66.0 66.0 67.0 66.0 66.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 07:58:00 INFO - PROCESS | 12856 | Values: 65.0 66.0 67.0 65.0 66.0 66.0 66.0 66.0 67.0 66.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:82.00 stddev:4.43 (5.4%) stddev-sans-first:4.56 07:58:00 INFO - PROCESS | 12856 | Values: 81.0 82.0 81.0 88.0 92.0 81.0 91.0 82.0 82.0 81.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:142.50 stddev:12.35 (8.7%) stddev-sans-first:13.07 07:58:00 INFO - PROCESS | 12856 | Values: 143.0 144.0 142.0 148.0 131.0 133.0 133.0 132.0 171.0 131.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:174.00 stddev:2.80 (1.6%) stddev-sans-first:2.96 07:58:00 INFO - PROCESS | 12856 | Values: 174.0 179.0 180.0 173.0 174.0 172.0 173.0 172.0 175.0 173.0 07:58:00 INFO - PROCESS | 12856 | 07:58:00 INFO - PROCESS | 12856 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:1.90 (2.7%) stddev-sans-first:2.01 07:58:00 INFO - PROCESS | 12856 | Values: 70.0 74.0 70.0 71.0 68.0 69.0 69.0 68.0 68.0 68.0 07:58:00 INFO - PROCESS | 12856 | -------- Summary: end -------- 07:58:00 INFO - PROCESS | 12856 | 07:58:01 INFO - TEST-INFO | 12856: exit 0 07:58:01 INFO - TEST-OK | kraken | took 64945ms 07:58:01 INFO - SUITE-END | took 1186s 07:58:01 INFO - Completed test suite (00:19:46) 07:58:01 INFO - javascript benchmark 07:58:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2581.083715281075, 2588.1976587257964, 2601.414681696617, 2611.490759301936, 2625.1861922803932, 4482.0, 4572.977135114324, 4593.379893714347, 4634.606058485028, 4707.9764601176985, 4534.5465453454635, 4564.42944631921, 4595.8621241362725, 4620.561046700558, 4635.652326075541, 4210.789460526977, 4222.535521092677, 4261.062566235423, 4323.913521729563, 4420.138073075752, 4340.717853339539, 4381.561843815609, 4428.490723566782, 4429.64562834974, 4433.357163211349, 4199.181159673864, 4246.554111818276, 4317.805698743564, 4327.805248763813, 4434.733915965053, 4198.958010419918, 4229.830806767725, 4281.593248641374, 4316.676249281285, 4338.197433474786, 4181.644560212355, 4194.559571245006, 4196.853110141161, 4197.265478541243, 4267.935980960288, 4512.887177820581, 4548.499665036877, 4555.111753208126, 4582.129395414894, 4634.050019745944, 4921.630877684188, 4957.851264462072, 4982.9252561211615, 4984.202527595604, 4986.950130498685, 4995.425526064513, 4998.050370429607, 4998.700077995332, 5018.04657115147, 5074.543291103819, 4575.062112266977, 4594.540545945379, 4682.999999999966, 4683.882902927454, 4686.531346865354, 5007.098722230034, 5073.315102461194, 5087.618428617869, 5154.278401023785, 5159.329287192641, 4874.46380898101, 4883.658143929891, 4890.046440944053, 4893.314935908976, 4958.107540642647, 4823.27650852368, 4909.410870695539, 4926.556609905054, 4935.8025678972435, 4945.8021679132435, 4910.116179087727, 4957.7769000395065, 4964.106460837084, 4994.675346102565, 5000.924986125211, 4908.779104940286, 4939.0, 4950.381202349707, 4951.925721114186, 5012.348394708664, 3927.7446965947124, 3954.169624378855, 4039.818208180639, 4072.959270407258, 4089.5501494835544, 3588.551431071116, 3606.7294952878638, 3629.0564453242346, 3665.8350374233223, 3708.3325001499447, 4390.626796722315, 4393.978030109829, 4395.340698895191, 4418.64650827933, 4449.555044495524, 4887.584555312767, 5048.066107770074, 5058.721770302669, 5059.316992206079, 5061.392632884077, 4278.935815962763, 4285.721428107163, 4308.74147551148, 4318.071614602875, 4380.014496738208, 4430.601245887886, 4431.7340959542535, 4462.598366146997, 4489.169503641837, 4491.438570178728, 4676.836310729108, 4677.064587082665, 4682.180618391836, 4682.742449165328, 4741.810327586858, 4843.128236917248, 4958.8760280993265, 4959.380077490314, 4962.057209130254, 5014.548690617862, 4729.5034021427255, 4731.479537250969, 4733.171694953438, 4779.211430113992, 4787.760611969457, 4935.284383764406, 4937.432195297587, 4939.357883475125, 4941.357623508921, 4998.750062496933, 4860.8298709545, 4943.406791185081, 5010.423801262901, 5014.323066385991, 5064.898702026013, 4861.027794441196, 4864.294677271847, 4876.171050921423, 4910.361652985089, 4939.75301234944, 4870.4155501340065, 4872.561469467765, 4872.780724867461, 4874.683145595525, 4925.113479573711, 4648.907021859476, 4777.617790576815, 4808.326834243138, 4818.036392721399, 4870.537298956593, 4896.510348965075, 4923.310736496965, 4923.729194894315, 4969.4782047884455, 4974.8756281093265, 4817.0606731686985, 4818.620235032825, 4826.251930950708, 4828.782704778364, 4880.975595122144], "value": 4568.1469200532465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.04771768699, 556.0935674849994, 559.5831105826159, 570.2102587915737, 574.6351067072408, 27992.720072799268, 37846.81076594617, 39511.80244098779, 39577.0, 43448.0, 39495.40756888649, 39975.80012099935, 40016.0, 40092.59907400925, 40111.197776044464, 7612.010438642982, 7796.1814009529035, 7800.453968222227, 7801.102873169573, 7801.102873169573, 7625.199354067826, 7702.537847729154, 7717.0, 7721.459497835153, 7724.497907636014, 7697.076350837908, 7780.766577002699, 7792.376609871225, 7796.688132474695, 7803.29770320671, 7720.07359116904, 7765.339946104588, 7774.416918731118, 7775.105862825791, 7783.104942931522, 7656.9234307657325, 7690.038745156856, 7701.152873183945, 7701.961490192569, 7704.6532906019365, 37539.62460375389, 37578.81210593957, 37600.62399375999, 37964.05089872747, 37980.81009594962, 3102.395032968572, 3119.485284927984, 3121.641011283709, 3253.9511907321407, 3601.981990090033, 28356.432871342662, 28729.281767955967, 28831.42337153245, 28845.42309153805, 30450.238744031354, 3331.5002749587516, 3332.450145725952, 3349.0, 3362.915927101842, 3380.577427821522, 254242.0, 254402.0, 255014.0, 255048.0, 255598.0, 30796.69203307983, 50636.493635063176, 50897.23654145191, 50932.49067509352, 51019.23471147936, 31217.68782312148, 32962.3407531848, 33005.17487062843, 33018.33963320768, 33085.83457082747, 13409.463621455228, 13611.659708507168, 13616.863831361758, 13617.319134043457, 13621.455141794217, 11050.723731906606, 11210.327380357205, 11241.775164496665, 11246.0, 11259.605913792979, 7579.431542634215, 7755.961220193975, 7813.062432508022, 7814.0, 7816.57008864518, 2983.612130423031, 3012.141539661186, 3018.7132222439272, 3056.251218451449, 3076.969230307668, 828.6892415344246, 880.8898887639045, 919.3105171121658, 921.2860033474111, 940.0270719805105, 26476.205713828615, 26862.731372686023, 27315.726842731714, 27473.0, 27768.58347124795, 830.4477522447626, 945.522511131874, 961.6682244625594, 1007.677543186188, 1030.5362586835954, 13670.794938075936, 14644.341004654816, 14839.319470699347, 14862.0, 14879.553613391616, 367433.0, 370702.0, 371063.0, 371461.0, 371644.977277382, 48157.75921120372, 48615.756921215165, 48624.0, 48641.513584864406, 48652.27021594679, 1064.904158625712, 1149.0807354116673, 1170.8243763435382, 1185.2118341302937, 1196.36592605919, 25233.0, 27729.861350693925, 28699.28251793722, 28995.27511812137, 29039.56440653434, 41017.384739228335, 41433.58566414418, 41511.37732933948, 41535.0, 41567.0, 281171.0, 281734.0, 281975.0, 283045.0, 283565.0, 21416.0, 21957.341279760993, 22154.113835446402, 22274.109035638394, 22302.21942232014, 40619.59380406158, 40783.0, 41135.1772964533, 41136.38295425511, 42834.35748463838, 34684.0, 34949.0, 35003.0, 35008.824955875054, 35516.64483355237, 34962.0, 35039.47440788339, 35159.0, 35366.115847104025, 35404.46893296551, 34129.65870341265, 34548.136296592784, 34700.0, 34924.12689682778, 35126.47310290399, 29565.408691826473, 32061.35877282488, 32146.839265803523, 32235.35529289448, 32654.673453265164, 24599.0, 24906.42973860312, 24999.99999999974, 25017.624735629342, 25240.242792716246], "value": 16097.186440529542, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8487065691094, 370.0895796341001, 370.51462584014945, 375.2832090706749, 387.52915208022245, 11887.643370698872, 15521.223938803058, 15900.52298431049, 17761.20074596643, 17995.37016204433, 16497.752533711973, 16520.504384868473, 16600.916995415006, 16615.169241537937, 16880.0, 4909.11635905536, 5035.169197082495, 5045.29365888774, 5062.417821950467, 5086.0, 3583.4803953426735, 3727.254549090193, 4857.635677324197, 5057.671251368668, 5058.190689489682, 3765.171662234318, 3769.7172712046568, 5219.921701174486, 5913.349531551547, 5923.200367950315, 5910.822675319704, 5920.555958303123, 5924.674142922137, 5925.940740592582, 5928.051511758141, 5147.6911385317, 5187.403448603383, 5261.053010458116, 5268.394134674523, 5279.815206467755, 19186.0, 19733.21067157312, 20007.299744508873, 21294.148234070617, 21476.140954361807, 1826.0, 1849.177116183299, 2202.977970220293, 2242.742084660252, 2363.0902102690416, 9730.708078757649, 10014.198864090855, 10839.945800270949, 12979.935100324532, 13642.52251171214, 1744.1540852686437, 1767.1517671517615, 1781.7594624725625, 1790.8746387752863, 1794.120880768427, 67804.0, 73336.0, 75164.99999999945, 75550.24449755542, 75990.24009759887, 9554.331196816156, 9575.23398128148, 9851.064148905842, 9857.162141217934, 9997.500124993721, 12756.553520626734, 12772.89247274208, 12789.23264604108, 12823.0, 12883.35583220854, 7101.502894797314, 7259.491835571459, 7287.234840341794, 7483.064616922885, 7514.511556748794, 6540.378664027005, 6657.234418041987, 6674.733010679615, 6925.826854328581, 7158.892616610755, 4012.1574469361763, 4060.7563546187325, 4137.710360274752, 4382.868513944587, 4402.801873915681, 1781.4922747017297, 1787.204693911223, 1800.7388928605542, 1802.9368972085913, 1897.6868816645374, 820.5035953248318, 845.4124383553371, 856.4647095565272, 877.5787621941377, 885.9291256699448, 16293.918530407509, 16601.83398166027, 17121.315147393965, 17132.486025419257, 17607.29570817153, 808.720991258015, 811.5090370325986, 845.2688424512875, 869.6390997735868, 879.6393478673841, 8385.832283354299, 9671.274654400948, 9728.75678108053, 9770.706878793648, 9895.356801807859, 363870.0, 366182.0, 367022.0, 368316.0, 391601.0419947882, 81154.59422702849, 81666.0, 81911.59044204741, 83229.0, 84332.21874016062, 876.8772371867943, 888.3071204460531, 889.4263200235812, 890.2655309369795, 890.8708237305684, 14416.855831441972, 15313.693726125191, 15384.38462461489, 15464.768028479357, 16254.674906501567, 38666.0, 38861.0, 39350.2129957405, 39494.60505394909, 39989.600103998586, 52368.73815630897, 52631.47368526266, 53107.20339194838, 55002.0, 56465.0, 7749.147593764568, 7758.7672369829, 7829.45193836426, 7879.448438609243, 8187.590620469072, 9113.3165012623, 11787.528498859949, 11828.940855295668, 11850.881491184979, 12057.698557536132, 10674.306170098918, 10742.24804263694, 10805.51375188134, 10856.10637245339, 10950.06924411434, 10074.042965918226, 10143.137833283958, 10443.216758743276, 10464.476776161313, 10536.947315263375, 9621.711348659552, 9861.506924653595, 9964.10323070927, 9993.700188994342, 10026.047525485068, 7663.693452261847, 7683.807904802201, 7780.688772449265, 7826.5695386751995, 7895.368370530229, 9942.751431214278, 10162.54268557902, 10188.286819922534, 10274.691759247235, 10776.353418794897], "value": 8207.291667833822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1218.4151607228537, 1326.1777697827345, 1363.40010395426, 1418.524794193945, 1670.7995040595122, 6987.860242795141, 7232.0959880015, 7302.306280903319, 7515.774526764192, 7543.811404714885, 7342.522736022168, 7431.405487560995, 7442.553446793183, 7452.776416707507, 7456.179820219785, 2178.58606864696, 2193.835462340323, 2208.2602328219987, 2225.944351391212, 2234.5866014787234, 2129.957400851982, 2167.5014746608213, 2199.813015893643, 2206.2719302630094, 2219.9556008879813, 2171.2834764527747, 2175.6845257437662, 2181.8472706910525, 2196.4508872781803, 2211.5403833469913, 2141.3468891987936, 2162.145952348821, 2168.035224325176, 2173.0981642618294, 2177.923772667965, 2100.5693832764246, 2133.200049981257, 2138.0485683870684, 2142.2716276466076, 2164.3723320237114, 7402.518836275625, 7474.289942455458, 7510.0, 7510.211427800112, 7539.0, 4499.235130027903, 4694.413198350207, 4702.694324868907, 4705.71765694056, 4708.0, 2860.0990687933236, 2959.733623973853, 3006.744426723731, 3017.0345489443166, 3020.7130322619314, 987.8962708915604, 993.7764003099317, 1009.1775203209361, 1010.1968934696934, 1011.1708399112731, 10388.688339349832, 10444.582216711322, 10455.268131230741, 10470.581176752921, 10475.685729428053, 4032.8790136295956, 4262.850800221977, 4415.205263052617, 4427.358033085185, 4428.955710442854, 8207.425480216447, 8288.668453261922, 8334.416590838584, 8349.54077525742, 8359.665613375519, 4598.517155698671, 4607.723536587749, 4616.792244349012, 4618.35343051973, 4619.260918253063, 3584.121890136933, 4170.103427763045, 4233.343831706091, 4286.442762440863, 4302.526722060571, 3535.41665625169, 3603.747737658339, 3739.7569158004644, 3745.9063523412133, 3765.171662234304, 1186.1578279421535, 1216.1243904388825, 1217.6468824041106, 1229.9631011069503, 1236.1717649175075, 799.5442597719244, 805.7824387415365, 807.5518087461397, 819.9016118065871, 825.7275099217244, 4982.07831551157, 5079.644424890296, 5120.948790512121, 5128.9230661540105, 5159.0, 413.78069623099805, 423.47489113499654, 428.5093567864777, 431.2883741826012, 435.1775144975998, 3473.6179020307745, 3538.292341531704, 3570.9285814283567, 3591.40741777609, 3637.8726744563814, 9162.083791620784, 9390.389624674373, 9469.053094690476, 9479.715608531755, 9486.051394860458, 8718.782030449289, 9143.817123657489, 9397.107274808883, 9403.57683904226, 9416.952915235517, 6990.790276291617, 7193.3166349196745, 7209.747658831916, 7210.567365958059, 7216.278372162847, 5123.590112791042, 5317.7341132943975, 5372.086745253238, 5372.113601255749, 5375.919361209507, 8597.742067737978, 8641.796341932766, 8686.609102590272, 8772.956135219283, 8775.166359195866, 8776.868346974928, 8802.163794439519, 8949.32880033987, 9004.729858104267, 9052.094790520896, 5847.883042339045, 6384.138141350858, 6520.836979075561, 6606.207255129415, 6793.796186114425, 7521.7743467696055, 7576.62116894142, 7589.468737188565, 7597.164311925794, 7598.772036838904, 6623.437007854193, 6625.900611490735, 6755.966220168868, 6794.320567943166, 6797.286284940208, 6532.706028228836, 6607.108040414675, 6634.83412914681, 6815.659217039228, 6847.691853866489, 6227.719752611234, 6248.937510625017, 6296.0, 6318.241810982711, 6321.304656487875, 4463.933041004322, 4491.079328737666, 4496.572825581565, 4625.352450656844, 4647.047355292225, 4285.828566857291, 4327.459067616548, 4427.380166776589, 4509.1658043262105, 4657.510961349009], "value": 4016.8133079061167, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.55141978513507, 295.30308472006067, 301.31150321515327, 302.30017509465574, 302.4827544898225, 4747.715137091773, 4766.7616619169075, 4795.856124316268, 4797.136515427222, 4799.25611530213, 4669.696469729465, 4689.507601701824, 4733.786979585919, 4737.478877323491, 4739.834105806298, 3762.8523300393367, 3776.150366167611, 3786.7692999775045, 3800.6579407853287, 3816.5420149582087, 3463.904337875587, 3803.220339830328, 3806.3719486284867, 3812.485314482551, 3822.2737679840743, 3666.3217304798563, 3675.191457879262, 3695.353313170198, 3697.9445308320264, 3699.9445008324897, 3664.5419322584676, 3673.559172899256, 3675.9632403675887, 3693.2059607184583, 3704.833282502294, 3040.8783648654025, 3118.5945827042565, 3187.6493585705784, 3263.4288999425007, 3276.3611095836322, 4549.7042692224895, 4551.5676010778925, 4564.931526027112, 4589.747563883985, 4607.401037865056, 4143.736160471055, 4152.211079894811, 4152.896177595584, 4154.792260386968, 4157.625813676784, 4603.332516785047, 4703.0, 4872.853814385574, 4928.704277743311, 4942.481039490874, 3465.7920524768597, 3485.5294535237927, 3489.4416893296952, 3495.667911548399, 3517.6306487818924, 5081.720505372203, 5122.308488354099, 5134.204198349262, 5136.357955255593, 5140.280360749498, 3392.9491057634154, 3600.3879340512176, 3604.9639503604626, 3606.124815096188, 3608.3685355063285, 3574.696150827157, 3599.884035948864, 3606.711463082947, 3642.5993140754495, 3644.5444319460066, 4150.522689890701, 4158.500979882433, 4160.625543701081, 4188.141431006637, 4191.476065491814, 4865.635077369211, 4880.951190488121, 4882.12121818069, 4906.871419573518, 4928.975355123273, 1237.5792230641446, 1259.785836407813, 1262.419287127913, 1270.9046821488425, 1272.5100836178137, 793.6269953122023, 802.7110240313483, 820.9876851847227, 822.8189798244376, 822.9753107406788, 4024.0, 4024.464587309976, 4040.2929487340184, 4043.757374557536, 4045.1505183911117, 4475.024108967566, 4507.594316511464, 4515.532451953129, 4547.931781023287, 4578.816847326137, 3965.72239943207, 3971.980140099281, 3972.0, 3977.781222032816, 3999.34010888206, 2320.581476769527, 2359.209664762324, 2395.6885604871604, 2403.1108489858866, 2415.3237093613816, 4285.635720963753, 4300.569943005675, 4566.49768525462, 4683.367745354333, 4807.903841923142, 5183.663061900965, 5193.169092945186, 5199.298094757235, 5201.0, 5241.344831896013, 4811.615070794398, 4825.445073816486, 4838.298446725275, 4850.089946032423, 4855.0, 4871.780769865435, 5025.497450254945, 5027.673201241908, 5037.647364684583, 5087.651772280275, 4926.891449423851, 4932.21084626458, 4935.272654570871, 4944.332515110414, 4947.975260123676, 4770.403699537558, 4789.61683065347, 4798.7840547174765, 4806.7836947336755, 4809.855704328876, 3410.0281419795238, 3529.9939517237462, 3562.2697347043277, 3575.12409459684, 3613.331533666175, 5024.070546948826, 5034.496550344937, 5038.47096054909, 5051.267566202953, 5063.3417655704525, 4921.926171107365, 4940.827071052496, 4944.0, 4952.182889823139, 4953.356063711694, 4728.479867214535, 4873.585745211697, 4923.335349727884, 4954.851354459372, 4955.40535135786, 4817.253325734517, 4824.469308376146, 4824.8793780155775, 4834.984653222863, 4861.0, 4908.558229759339, 4959.752012399438, 4981.8007279709855, 4982.604870636228, 4994.1260279450225, 4625.722456652477, 4796.136695394863, 4816.34979277793, 4882.780274887567, 4883.975580122076], "value": 3668.5797622486757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.8400399900025, 665.6538599928037, 666.7999600119964, 700.9555761914746, 734.7391675955035, 13206.075574709774, 14348.713025739478, 14371.209583472904, 14390.928045359758, 14408.423663053478, 13438.19370837748, 13557.0, 13576.253306068162, 13587.252701101435, 13629.386677599507, 612.6446660936668, 736.3556887723229, 746.7908985484073, 766.6588368176164, 768.5657603454058, 768.0322793280479, 772.1082150116624, 773.0105465004775, 774.094309657699, 774.2412435812906, 809.1625167951172, 810.2505182706003, 810.9229623185788, 810.9351251899864, 811.2941740685612, 636.4017823246157, 649.5128653509868, 650.5023656902473, 654.9148610680629, 812.5368539932241, 573.160320131006, 586.1676419484343, 588.1794896119932, 590.8493334199752, 591.5326891755508, 12781.61655150347, 12813.615591532269, 12818.359082045861, 12859.678508037281, 12866.614001579968, 11987.820182697267, 12420.503179872885, 12753.0, 13181.60455186346, 13285.86714132856, 11527.365994870279, 11962.940185299105, 12269.509219631203, 12381.938090309492, 12754.426050827735, 4089.754614723126, 4126.546079931205, 4296.806643701041, 4318.784060796947, 4338.978305108486, 65187.0, 65237.02144467789, 65332.0, 65502.0, 65620.0, 9462.337636365388, 9520.761980950394, 9520.809898762654, 10230.59077636901, 10634.840477392845, 17666.646667066587, 17858.732119018227, 18045.909770451064, 18111.72832407515, 18397.54006149857, 6889.379955804002, 7001.264867188974, 7017.964910175417, 7042.330978557029, 7234.6382680865745, 3611.530501034849, 3624.7100231981376, 3706.5181526401393, 3725.627437256307, 3767.811609419518, 2858.957115643267, 2935.6770755216908, 2941.4558306713325, 2949.1742312152633, 2952.099609619087, 504.2109099259647, 529.4731741916818, 538.5906710899744, 561.7724821447321, 586.8386193796655, 496.9602431805447, 507.52546369144966, 536.2974503400558, 579.2932622200908, 581.2443823030044, 7659.4255430841795, 7890.802729931683, 7954.284114429729, 8286.295664868554, 8609.655613775503, 485.26482379195545, 500.4320096690283, 553.4244385838763, 556.9164625306155, 576.1127863090793, 3619.1856832212543, 3901.2197560488016, 3910.570075983569, 4032.1532478179324, 4230.513490948519, 85046.02338918108, 93200.53399732958, 93224.53387733018, 95114.52442737742, 95170.0, 83452.0, 84050.57974710087, 84594.5770271157, 84994.15005849862, 85673.0, 75331.0, 75577.0, 76146.85779713308, 76181.61909190418, 76364.61817690988, 7459.328660420588, 7576.211894052974, 7995.1605081465605, 8001.279884810395, 8240.13478584764, 41451.0, 42895.9276018102, 43950.0, 44305.55694443014, 44808.327875081246, 56799.43200567942, 65511.672441637485, 65934.34065659282, 65953.34046659472, 66118.33881661315, 9485.95654477993, 9520.61917523319, 9534.284928630243, 9583.712488625353, 9701.417914925127, 10467.057964783206, 10472.633457829244, 10562.366258024544, 11268.605598804003, 11368.147388946027, 10875.456227188768, 11063.778724425627, 11408.258463199865, 11578.768424631291, 11589.594364197214, 9511.61953521879, 9655.758606034906, 9932.056454636799, 10379.11777498921, 10383.584656613652, 10258.333208341433, 10577.524011419659, 10689.251752377259, 11037.558497660324, 11079.612213572485, 8827.16141966512, 8888.955555222183, 9075.500847453453, 9307.813843723223, 9314.813703726024, 9085.818283634422, 9442.10300021497, 9508.239340852853, 9549.52252387364, 9721.902780972383], "value": 6429.767482095198, "unit": "score"}], "name": "dromaeo_css", "value": 6207.055982542772, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 136.0, 115.0, 113.0, 115.0, 114.0, 113.0, 116.0, 113.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 126.0, 152.0, 187.0, 124.0, 159.0, 152.0, 128.0, 178.0, 127.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 161.0, 162.0, 161.0, 162.0, 162.0, 163.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 165.0, 76.0, 77.0, 75.0, 77.0, 157.0, 76.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 151.0, 84.0, 83.0, 84.0, 147.0, 83.0, 83.0, 143.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 88.0, 92.0, 81.0, 91.0, 82.0, 82.0, 81.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 142.0, 148.0, 131.0, 133.0, 133.0, 132.0, 171.0, 131.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 179.0, 180.0, 173.0, 174.0, 172.0, 173.0, 172.0, 175.0, 173.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 70.0, 71.0, 68.0, 69.0, 69.0, 68.0, 68.0, 68.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1582.3, "alertThreshold": 2.0}]} 07:58:02 INFO - Return code: 0 07:58:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:58:02 INFO - # TBPL SUCCESS # 07:58:02 INFO - Running post-action listener: _resource_record_post_action 07:58:02 INFO - [mozharness: 2016-08-31 14:58:02.076867Z] Finished run-tests step (success) 07:58:02 INFO - Running post-run listener: _resource_record_post_run 07:58:02 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405331968; Read time: 16; Write time: 324940 07:58:02 INFO - TinderboxPrint: CPU usage
13.0% 07:58:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 07:58:02 INFO - TinderboxPrint: I/O write bytes / time
405,331,968 / 324,940 07:58:02 INFO - TinderboxPrint: CPU idle
8,344.6 (86.9%) 07:58:02 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 07:58:02 INFO - TinderboxPrint: Swap in / out
0 / 0 07:58:02 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114298880; Read time: 0; Write time: 119624 07:58:02 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291033088; Read time: 0; Write time: 205316 07:58:02 INFO - Running post-run listener: _upload_blobber_files 07:58:02 INFO - Blob upload gear active. 07:58:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:58:02 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 07:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:58:03 INFO - (blobuploader) - INFO - Open directory for files ... 07:58:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:58:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:07 INFO - (blobuploader) - INFO - Done attempting. 07:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:08 INFO - (blobuploader) - INFO - Done attempting. 07:58:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:58:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:08 INFO - (blobuploader) - INFO - Done attempting. 07:58:08 INFO - (blobuploader) - INFO - Iteration through files over. 07:58:09 INFO - Return code: 0 07:58:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:58:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:58:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7151f6629902cfc5ec0a859c0d8dc0bb6e5bab4aa13d5c3cf31b5b7c02e308a1845f1dea84d51320f1e9bd274f4a7b3f5cece2efc147ad020a01c2fbf858ad17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0f90f4b19441023530385b1bbc222a919737cbd213815f067d126c8d943475622514a72f2427050a3ec1917d5ec1010d90a9b4cadd2bb4c8b582074d0abcdfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55369d712c6baeb9a055331020cb08c9808f928e7f7e1f440783701b9343030a780ad131f9d43ef1f669282b832bc7ee0e03153d1975c59218c80b8c9a5ae430"} 07:58:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:58:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:58:09 INFO - Contents: 07:58:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7151f6629902cfc5ec0a859c0d8dc0bb6e5bab4aa13d5c3cf31b5b7c02e308a1845f1dea84d51320f1e9bd274f4a7b3f5cece2efc147ad020a01c2fbf858ad17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0f90f4b19441023530385b1bbc222a919737cbd213815f067d126c8d943475622514a72f2427050a3ec1917d5ec1010d90a9b4cadd2bb4c8b582074d0abcdfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55369d712c6baeb9a055331020cb08c9808f928e7f7e1f440783701b9343030a780ad131f9d43ef1f669282b832bc7ee0e03153d1975c59218c80b8c9a5ae430"} 07:58:09 INFO - Running post-run listener: copy_logs_to_upload_dir 07:58:09 INFO - Copying logs to upload dir... 07:58:09 INFO - mkdir: /builds/slave/test/build/upload/logs 07:58:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.179566 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 07:58:07.938148) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 07:58:07.941563) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7151f6629902cfc5ec0a859c0d8dc0bb6e5bab4aa13d5c3cf31b5b7c02e308a1845f1dea84d51320f1e9bd274f4a7b3f5cece2efc147ad020a01c2fbf858ad17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0f90f4b19441023530385b1bbc222a919737cbd213815f067d126c8d943475622514a72f2427050a3ec1917d5ec1010d90a9b4cadd2bb4c8b582074d0abcdfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55369d712c6baeb9a055331020cb08c9808f928e7f7e1f440783701b9343030a780ad131f9d43ef1f669282b832bc7ee0e03153d1975c59218c80b8c9a5ae430"} build_url:https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/K7mL7zEqTNaI1e_xRkDUTA/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/Firefox-Non-PGO/sha512/7151f6629902cfc5ec0a859c0d8dc0bb6e5bab4aa13d5c3cf31b5b7c02e308a1845f1dea84d51320f1e9bd274f4a7b3f5cece2efc147ad020a01c2fbf858ad17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0f90f4b19441023530385b1bbc222a919737cbd213815f067d126c8d943475622514a72f2427050a3ec1917d5ec1010d90a9b4cadd2bb4c8b582074d0abcdfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55369d712c6baeb9a055331020cb08c9808f928e7f7e1f440783701b9343030a780ad131f9d43ef1f669282b832bc7ee0e03153d1975c59218c80b8c9a5ae430"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 07:58:07.967550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:58:07.967965) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472654239.310334-808059892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 07:58:08.052381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 07:58:08.052739) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 07:58:08.053101) ========= ========= Total master_lag: 0.47 =========