builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1474329284.56 results: success (0) buildid: 20160919155316 builduid: e5603188fa624d6ebfe140cd6f311066 revision: c9971be9e98150ef99d4ef80c6f800ec5915b1ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.555788) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.556306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.556629) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.571208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.571515) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 16:54:44-- 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% 37.3M=0s 2016-09-19 16:54:44 (37.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087717 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.675484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.676022) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017473 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:44.730072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 16:54:44.730879) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c9971be9e98150ef99d4ef80c6f800ec5915b1ac --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 c9971be9e98150ef99d4ef80c6f800ec5915b1ac --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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 16:54:44,941 truncating revision to first 12 chars 2016-09-19 16:54:44,941 Setting DEBUG logging. 2016-09-19 16:54:44,941 attempt 1/10 2016-09-19 16:54:44,941 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c9971be9e981?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 16:54:46,298 unpacking tar archive at: mozilla-central-c9971be9e981/testing/mozharness/ program finished with exit code 0 elapsedTime=1.701646 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 16:54:46.446606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:46.447006) ========= script_repo_revision: c9971be9e98150ef99d4ef80c6f800ec5915b1ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:46.447470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:46.447806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:54:46.457809) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-19 16:54:46.458123) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:54:46 INFO - MultiFileLogger online at 20160919 16:54:46 in /builds/slave/test 16:54:46 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 16:54:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:54:46 INFO - {'append_to_log': False, 16:54:46 INFO - 'base_work_dir': '/builds/slave/test', 16:54:46 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:54:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:54:46 INFO - 'branch': 'Firefox-Non-PGO', 16:54:46 INFO - 'buildbot_json_path': 'buildprops.json', 16:54:46 INFO - 'config_files': ('talos/linux_config.py',), 16:54:46 INFO - 'default_actions': ('clobber', 16:54:46 INFO - 'read-buildbot-config', 16:54:46 INFO - 'download-and-extract', 16:54:46 INFO - 'populate-webroot', 16:54:46 INFO - 'create-virtualenv', 16:54:46 INFO - 'install', 16:54:46 INFO - 'run-tests'), 16:54:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:54:46 INFO - 'download_minidump_stackwalk': True, 16:54:46 INFO - 'download_symbols': 'ondemand', 16:54:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:54:46 INFO - 'tooltool.py': '/tools/tooltool.py', 16:54:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:54:46 INFO - '/tools/misc-python/virtualenv.py')}, 16:54:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:54:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:54:46 INFO - 'installer_path': 'installer.exe', 16:54:46 INFO - 'log_level': 'info', 16:54:46 INFO - 'log_name': 'talos', 16:54:46 INFO - 'log_to_console': True, 16:54:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:54:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:54:46 INFO - 'opt_config_files': (), 16:54:46 INFO - 'pip_index': False, 16:54:46 INFO - 'sps_profile': False, 16:54:46 INFO - 'sps_profile_interval': 0, 16:54:46 INFO - 'suite': 'dromaeojs', 16:54:46 INFO - 'system_bits': '32', 16:54:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:54:46 INFO - 'title': 'talos-linux64-ix-037', 16:54:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:54:46 INFO - 'use_talos_json': True, 16:54:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:54:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:54:46 INFO - 'work_dir': 'build'} 16:54:46 INFO - [mozharness: 2016-09-19 23:54:46.757459Z] Running clobber step. 16:54:46 INFO - Running pre-action listener: _resource_record_pre_action 16:54:46 INFO - Running main action method: clobber 16:54:46 INFO - rmtree: /builds/slave/test/build 16:54:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:54:47 INFO - Running post-action listener: _resource_record_post_action 16:54:47 INFO - [mozharness: 2016-09-19 23:54:47.216353Z] Finished clobber step (success) 16:54:47 INFO - [mozharness: 2016-09-19 23:54:47.216454Z] Running read-buildbot-config step. 16:54:47 INFO - Running pre-action listener: _resource_record_pre_action 16:54:47 INFO - Running main action method: read_buildbot_config 16:54:47 INFO - Using buildbot properties: 16:54:47 INFO - { 16:54:47 INFO - "project": "", 16:54:47 INFO - "product": "firefox", 16:54:47 INFO - "script_repo_revision": "production", 16:54:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:54:47 INFO - "repository": "", 16:54:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:54:47 INFO - "buildid": "20160919155316", 16:54:47 INFO - "pgo_build": "False", 16:54:47 INFO - "basedir": "/builds/slave/test", 16:54:47 INFO - "buildnumber": 647, 16:54:47 INFO - "slavename": "talos-linux64-ix-037", 16:54:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:54:47 INFO - "platform": "ubuntu64_hw", 16:54:47 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:54:47 INFO - "branch": "mozilla-central", 16:54:47 INFO - "repo_path": "mozilla-central", 16:54:47 INFO - "revision": "c9971be9e98150ef99d4ef80c6f800ec5915b1ac", 16:54:47 INFO - "stage_platform": "linux64", 16:54:47 INFO - "builduid": "e5603188fa624d6ebfe140cd6f311066", 16:54:47 INFO - "slavebuilddir": "test" 16:54:47 INFO - } 16:54:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 16:54:47 INFO - Running post-action listener: _resource_record_post_action 16:54:47 INFO - [mozharness: 2016-09-19 23:54:47.218901Z] Finished read-buildbot-config step (success) 16:54:47 INFO - [mozharness: 2016-09-19 23:54:47.218993Z] Running download-and-extract step. 16:54:47 INFO - Running pre-action listener: _resource_record_pre_action 16:54:47 INFO - Running main action method: download_and_extract 16:54:47 INFO - mkdir: /builds/slave/test/build/tests 16:54: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')]} 16:54:47 INFO - trying https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 16:54:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 16:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:54:47 INFO - Downloaded 1361 bytes. 16:54:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 16:54:47 INFO - Using the following test package requirements: 16:54:47 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 16:54:47 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:54:47 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:54:47 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'jsshell-linux-x86_64.zip'], 16:54:47 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:54:47 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 16:54:47 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:54:47 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:54:47 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:54:47 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:54:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:54:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:54:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 16:54:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:54:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:54:48 INFO - Expected file size: 20365853 16:54:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:54:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 16:54:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:54:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:54:53 INFO - Expected file size: 20365853 16:54:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 16:54:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:54:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:54:58 INFO - Expected file size: 14414059 16:54:59 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')]} 16:54:59 INFO - trying https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:55:00 INFO - Downloaded 55596839 bytes. 16:55:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:55:00 INFO - mkdir: /builds/slave/test/properties 16:55:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:55:00 INFO - Writing to file /builds/slave/test/properties/build_url 16:55:00 INFO - Contents: 16:55:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:55:00 INFO - Running post-action listener: _resource_record_post_action 16:55:00 INFO - Running post-action listener: set_extra_try_arguments 16:55:00 INFO - [mozharness: 2016-09-19 23:55:00.881655Z] Finished download-and-extract step (success) 16:55:00 INFO - [mozharness: 2016-09-19 23:55:00.881884Z] Running populate-webroot step. 16:55:00 INFO - Running pre-action listener: _resource_record_pre_action 16:55:00 INFO - Running main action method: populate_webroot 16:55:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:55:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:55:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:55:00 INFO - '--tppagecycles', 16:55:00 INFO - '7'], 16:55:00 INFO - 'tests': ['tsvgm']}}, 16:55:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:55:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:55:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:55:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:55:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['damp', 'tps']}, 16:55:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'tests': ['damp', 'tps']}, 16:55:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['dromaeo_dom']}, 16:55:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:55:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['basic_compositor_video']}, 16:55:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:55:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tsvgx', 16:55:00 INFO - 'tsvgr_opacity', 16:55:00 INFO - 'tart', 16:55:00 INFO - 'tscrollx', 16:55:00 INFO - 'cart']}, 16:55:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:55:00 INFO - 'tsvgr_opacity', 16:55:00 INFO - 'tart', 16:55:00 INFO - 'tscrollx', 16:55:00 INFO - 'cart']}, 16:55:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tp5o']}, 16:55:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'tests': ['tp5o']}, 16:55:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s', 16:55:00 INFO - '--xperf_path', 16:55:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:00 INFO - 'tests': ['tp5n']}, 16:55:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:00 INFO - 'talos_options': ['--xperf_path', 16:55:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:00 INFO - 'tests': ['tp5n']}}, 16:55:00 INFO - 'talos.zip': {'path': '', 16:55:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:55:00 INFO - Running post-action listener: _resource_record_post_action 16:55:00 INFO - [mozharness: 2016-09-19 23:55:00.901049Z] Finished populate-webroot step (success) 16:55:00 INFO - [mozharness: 2016-09-19 23:55:00.901138Z] Running create-virtualenv step. 16:55:00 INFO - Running pre-action listener: _resource_record_pre_action 16:55:00 INFO - Running main action method: create_virtualenv 16:55:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:55:00 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 16:55:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:55:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:55:01 INFO - Using real prefix '/usr' 16:55:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:55:01 INFO - Installing distribute.............................................................................................................................................................................................done. 16:55:03 INFO - Installing pip.................done. 16:55:03 INFO - Return code: 0 16:55:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:03 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 16:55:03 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 16:55:03 INFO - Using env: {'DISPLAY': ':0', 16:55:03 INFO - 'HOME': '/home/cltbld', 16:55:03 INFO - 'LANG': 'en_US.UTF-8', 16:55:03 INFO - 'LANGUAGE': 'en_US:en', 16:55:03 INFO - 'LOGNAME': 'cltbld', 16:55:03 INFO - 'MAIL': '/var/mail/cltbld', 16:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:03 INFO - 'MOZ_NO_REMOTE': '1', 16:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:03 INFO - 'NO_EM_RESTART': '1', 16:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:03 INFO - 'PWD': '/builds/slave/test', 16:55:03 INFO - 'SHELL': '/bin/bash', 16:55:03 INFO - 'SHLVL': '1', 16:55:03 INFO - 'TERM': 'linux', 16:55:03 INFO - 'TMOUT': '86400', 16:55:03 INFO - 'USER': 'cltbld', 16:55:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:03 INFO - '_': '/tools/buildbot/bin/python'} 16:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:03 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 16:55:03 INFO - Cleaning up... 16:55:03 INFO - Return code: 0 16:55:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:03 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 16:55:03 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 16:55:03 INFO - Using env: {'DISPLAY': ':0', 16:55:03 INFO - 'HOME': '/home/cltbld', 16:55:03 INFO - 'LANG': 'en_US.UTF-8', 16:55:03 INFO - 'LANGUAGE': 'en_US:en', 16:55:03 INFO - 'LOGNAME': 'cltbld', 16:55:03 INFO - 'MAIL': '/var/mail/cltbld', 16:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:03 INFO - 'MOZ_NO_REMOTE': '1', 16:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:03 INFO - 'NO_EM_RESTART': '1', 16:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:03 INFO - 'PWD': '/builds/slave/test', 16:55:03 INFO - 'SHELL': '/bin/bash', 16:55:03 INFO - 'SHLVL': '1', 16:55:03 INFO - 'TERM': 'linux', 16:55:03 INFO - 'TMOUT': '86400', 16:55:03 INFO - 'USER': 'cltbld', 16:55:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:03 INFO - '_': '/tools/buildbot/bin/python'} 16:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:03 INFO - Downloading/unpacking psutil>=3.1.1 16:55:03 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 16:55:03 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 16:55:03 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 16:55:03 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 16:55:03 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 16:55:03 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 16:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:05 INFO - Installing collected packages: psutil 16:55:05 INFO - Running setup.py install for psutil 16:55:05 INFO - building 'psutil._psutil_linux' extension 16:55:05 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 16:55:06 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 16:55:06 INFO - building 'psutil._psutil_posix' extension 16:55:06 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 16:55:06 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 16:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:06 INFO - Successfully installed psutil 16:55:06 INFO - Cleaning up... 16:55:06 INFO - Return code: 0 16:55:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:06 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:06 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 16:55:06 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 16:55:06 INFO - Using env: {'DISPLAY': ':0', 16:55:06 INFO - 'HOME': '/home/cltbld', 16:55:06 INFO - 'LANG': 'en_US.UTF-8', 16:55:06 INFO - 'LANGUAGE': 'en_US:en', 16:55:06 INFO - 'LOGNAME': 'cltbld', 16:55:06 INFO - 'MAIL': '/var/mail/cltbld', 16:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:06 INFO - 'MOZ_NO_REMOTE': '1', 16:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:06 INFO - 'NO_EM_RESTART': '1', 16:55:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:06 INFO - 'PWD': '/builds/slave/test', 16:55:06 INFO - 'SHELL': '/bin/bash', 16:55:06 INFO - 'SHLVL': '1', 16:55:06 INFO - 'TERM': 'linux', 16:55:06 INFO - 'TMOUT': '86400', 16:55:06 INFO - 'USER': 'cltbld', 16:55:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:06 INFO - '_': '/tools/buildbot/bin/python'} 16:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:55:06 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 16:55:06 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 16:55:06 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 16:55:06 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 16:55:06 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 16:55:06 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 16:55:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:55:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:55:08 INFO - Installing collected packages: mozsystemmonitor 16:55:08 INFO - Running setup.py install for mozsystemmonitor 16:55:08 INFO - Successfully installed mozsystemmonitor 16:55:08 INFO - Cleaning up... 16:55:08 INFO - Return code: 0 16:55:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:08 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:08 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 16:55:08 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 16:55:08 INFO - Using env: {'DISPLAY': ':0', 16:55:08 INFO - 'HOME': '/home/cltbld', 16:55:08 INFO - 'LANG': 'en_US.UTF-8', 16:55:08 INFO - 'LANGUAGE': 'en_US:en', 16:55:08 INFO - 'LOGNAME': 'cltbld', 16:55:08 INFO - 'MAIL': '/var/mail/cltbld', 16:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:08 INFO - 'MOZ_NO_REMOTE': '1', 16:55:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:08 INFO - 'NO_EM_RESTART': '1', 16:55:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:08 INFO - 'PWD': '/builds/slave/test', 16:55:08 INFO - 'SHELL': '/bin/bash', 16:55:08 INFO - 'SHLVL': '1', 16:55:08 INFO - 'TERM': 'linux', 16:55:08 INFO - 'TMOUT': '86400', 16:55:08 INFO - 'USER': 'cltbld', 16:55:08 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:08 INFO - '_': '/tools/buildbot/bin/python'} 16:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:08 INFO - Downloading/unpacking jsonschema==2.5.1 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:55:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:55:10 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 16:55:10 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 16:55:10 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 16:55:10 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 16:55:10 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 16:55:10 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 16:55:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:55:11 INFO - warning: no files found matching '*.txt' 16:55:11 INFO - no previously-included directories found matching 'build' 16:55:11 INFO - no previously-included directories found matching 'dist' 16:55:11 INFO - no previously-included directories found matching '.git*' 16:55:11 INFO - Installing collected packages: jsonschema, functools32 16:55:11 INFO - Running setup.py install for functools32 16:55:11 INFO - warning: no files found matching '*.txt' 16:55:11 INFO - no previously-included directories found matching 'build' 16:55:11 INFO - no previously-included directories found matching 'dist' 16:55:11 INFO - no previously-included directories found matching '.git*' 16:55:11 INFO - Successfully installed jsonschema functools32 16:55:11 INFO - Cleaning up... 16:55:11 INFO - Return code: 0 16:55:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:55:11 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')]} 16:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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')]} 16:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:11 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 16:55:11 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 16:55:11 INFO - Using env: {'DISPLAY': ':0', 16:55:11 INFO - 'HOME': '/home/cltbld', 16:55:11 INFO - 'LANG': 'en_US.UTF-8', 16:55:11 INFO - 'LANGUAGE': 'en_US:en', 16:55:11 INFO - 'LOGNAME': 'cltbld', 16:55:11 INFO - 'MAIL': '/var/mail/cltbld', 16:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:11 INFO - 'MOZ_NO_REMOTE': '1', 16:55:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:11 INFO - 'NO_EM_RESTART': '1', 16:55:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:11 INFO - 'PWD': '/builds/slave/test', 16:55:11 INFO - 'SHELL': '/bin/bash', 16:55:11 INFO - 'SHLVL': '1', 16:55:11 INFO - 'TERM': 'linux', 16:55:11 INFO - 'TMOUT': '86400', 16:55:11 INFO - 'USER': 'cltbld', 16:55:11 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:11 INFO - '_': '/tools/buildbot/bin/python'} 16:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:55:11 INFO - Cleaning up... 16:55:11 INFO - Return code: 0 16:55:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:55:11 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')]} 16:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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')]} 16:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:11 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 16:55:11 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 16:55:11 INFO - Using env: {'DISPLAY': ':0', 16:55:11 INFO - 'HOME': '/home/cltbld', 16:55:11 INFO - 'LANG': 'en_US.UTF-8', 16:55:11 INFO - 'LANGUAGE': 'en_US:en', 16:55:11 INFO - 'LOGNAME': 'cltbld', 16:55:11 INFO - 'MAIL': '/var/mail/cltbld', 16:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:11 INFO - 'MOZ_NO_REMOTE': '1', 16:55:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:11 INFO - 'NO_EM_RESTART': '1', 16:55:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:11 INFO - 'PWD': '/builds/slave/test', 16:55:11 INFO - 'SHELL': '/bin/bash', 16:55:11 INFO - 'SHLVL': '1', 16:55:11 INFO - 'TERM': 'linux', 16:55:11 INFO - 'TMOUT': '86400', 16:55:11 INFO - 'USER': 'cltbld', 16:55:11 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:11 INFO - '_': '/tools/buildbot/bin/python'} 16:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:55:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:55:13 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 16:55:13 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 16:55:13 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 16:55:13 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 16:55:13 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 16:55:13 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 16:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:55:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:55:14 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 16:55:14 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 16:55:14 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 16:55:14 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 16:55:14 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 16:55:14 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 16:55:14 INFO - Downloading docopt-0.6.1.tar.gz 16:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:55:14 INFO - Installing collected packages: blobuploader, requests, docopt 16:55:14 INFO - Running setup.py install for blobuploader 16:55:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:55:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:55:14 INFO - Running setup.py install for requests 16:55:15 INFO - Running setup.py install for docopt 16:55:15 INFO - Successfully installed blobuploader requests docopt 16:55:15 INFO - Cleaning up... 16:55:15 INFO - Return code: 0 16:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:15 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')]} 16:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:15 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')]} 16:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:15 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:15 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 16:55:15 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 16:55:15 INFO - Using env: {'DISPLAY': ':0', 16:55:15 INFO - 'HOME': '/home/cltbld', 16:55:15 INFO - 'LANG': 'en_US.UTF-8', 16:55:15 INFO - 'LANGUAGE': 'en_US:en', 16:55:15 INFO - 'LOGNAME': 'cltbld', 16:55:15 INFO - 'MAIL': '/var/mail/cltbld', 16:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:15 INFO - 'MOZ_NO_REMOTE': '1', 16:55:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:15 INFO - 'NO_EM_RESTART': '1', 16:55:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:15 INFO - 'PWD': '/builds/slave/test', 16:55:15 INFO - 'SHELL': '/bin/bash', 16:55:15 INFO - 'SHLVL': '1', 16:55:15 INFO - 'TERM': 'linux', 16:55:15 INFO - 'TMOUT': '86400', 16:55:15 INFO - 'USER': 'cltbld', 16:55:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:15 INFO - '_': '/tools/buildbot/bin/python'} 16:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:15 INFO - Running setup.py (path:/tmp/pip-VDmvxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:15 INFO - Running setup.py (path:/tmp/pip-djBp0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:15 INFO - Running setup.py (path:/tmp/pip-2vaIlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:15 INFO - Running setup.py (path:/tmp/pip-1GlZHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:15 INFO - Running setup.py (path:/tmp/pip-UbzDjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:15 INFO - Running setup.py (path:/tmp/pip-_pl1JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:15 INFO - Running setup.py (path:/tmp/pip-njFmCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:15 INFO - Running setup.py (path:/tmp/pip-C_dHX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:16 INFO - Running setup.py (path:/tmp/pip-6RgO9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:16 INFO - Running setup.py (path:/tmp/pip-xXztNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:16 INFO - Running setup.py (path:/tmp/pip-dQ8QPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:16 INFO - Running setup.py (path:/tmp/pip-AylAO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:16 INFO - Running setup.py (path:/tmp/pip-Hg5AYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:16 INFO - Running setup.py (path:/tmp/pip-Zv8rSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:16 INFO - Running setup.py (path:/tmp/pip-3iKezh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:16 INFO - Running setup.py (path:/tmp/pip-Tszsps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:16 INFO - Running setup.py (path:/tmp/pip-0jsL71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:55:16 INFO - Running setup.py install for manifestparser 16:55:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:55:16 INFO - Running setup.py install for mozcrash 16:55:16 INFO - Running setup.py install for mozdebug 16:55:17 INFO - Running setup.py install for mozdevice 16:55:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozfile 16:55:17 INFO - Running setup.py install for mozhttpd 16:55:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozinfo 16:55:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozInstall 16:55:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozleak 16:55:17 INFO - Running setup.py install for mozlog 16:55:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for moznetwork 16:55:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozprocess 16:55:17 INFO - Running setup.py install for mozprofile 16:55:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Running setup.py install for mozrunner 16:55:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Running setup.py install for mozscreenshot 16:55:18 INFO - Running setup.py install for moztest 16:55:18 INFO - Running setup.py install for mozversion 16:55:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:55:18 INFO - Cleaning up... 16:55:18 INFO - Return code: 0 16:55:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:18 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:18 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 16:55:18 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 16:55:18 INFO - Using env: {'DISPLAY': ':0', 16:55:18 INFO - 'HOME': '/home/cltbld', 16:55:18 INFO - 'LANG': 'en_US.UTF-8', 16:55:18 INFO - 'LANGUAGE': 'en_US:en', 16:55:18 INFO - 'LOGNAME': 'cltbld', 16:55:18 INFO - 'MAIL': '/var/mail/cltbld', 16:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:18 INFO - 'MOZ_NO_REMOTE': '1', 16:55:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:18 INFO - 'NO_EM_RESTART': '1', 16:55:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:18 INFO - 'PWD': '/builds/slave/test', 16:55:18 INFO - 'SHELL': '/bin/bash', 16:55:18 INFO - 'SHLVL': '1', 16:55:18 INFO - 'TERM': 'linux', 16:55:18 INFO - 'TMOUT': '86400', 16:55:18 INFO - 'USER': 'cltbld', 16:55:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:18 INFO - '_': '/tools/buildbot/bin/python'} 16:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:18 INFO - Running setup.py (path:/tmp/pip-ItNRb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:18 INFO - Running setup.py (path:/tmp/pip-dkaLcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:18 INFO - Running setup.py (path:/tmp/pip-1xL_QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:18 INFO - Running setup.py (path:/tmp/pip-6WqpCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:19 INFO - Running setup.py (path:/tmp/pip-BwGYbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:19 INFO - Running setup.py (path:/tmp/pip-Fe5z7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:19 INFO - Running setup.py (path:/tmp/pip-_Q23EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:19 INFO - Running setup.py (path:/tmp/pip-NnZQ7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:19 INFO - Running setup.py (path:/tmp/pip-D0FhrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:19 INFO - Running setup.py (path:/tmp/pip-xhPXdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:19 INFO - Running setup.py (path:/tmp/pip-HaBo5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:19 INFO - Running setup.py (path:/tmp/pip-M83Ukf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:19 INFO - Running setup.py (path:/tmp/pip-dGrRJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:19 INFO - Running setup.py (path:/tmp/pip-12Ohvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:19 INFO - Running setup.py (path:/tmp/pip-mhL5Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:19 INFO - Running setup.py (path:/tmp/pip-CuN8R0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:19 INFO - Running setup.py (path:/tmp/pip-7uKAY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:21 INFO - Downloading blessings-1.6.tar.gz 16:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:55:21 INFO - Installing collected packages: blessings 16:55:21 INFO - Running setup.py install for blessings 16:55:22 INFO - Successfully installed blessings 16:55:22 INFO - Cleaning up... 16:55:22 INFO - Return code: 0 16:55:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:22 INFO - Reading from file tmpfile_stdout 16:55:22 INFO - Current package versions: 16:55:22 INFO - argparse == 1.2.1 16:55:22 INFO - blessings == 1.6 16:55:22 INFO - blobuploader == 1.2.4 16:55:22 INFO - docopt == 0.6.1 16:55:22 INFO - functools32 == 3.2.3-2 16:55:22 INFO - jsonschema == 2.5.1 16:55:22 INFO - manifestparser == 1.1 16:55:22 INFO - mozInstall == 1.12 16:55:22 INFO - mozcrash == 0.17 16:55:22 INFO - mozdebug == 0.1 16:55:22 INFO - mozdevice == 0.48 16:55:22 INFO - mozfile == 1.2 16:55:22 INFO - mozhttpd == 0.7 16:55:22 INFO - mozinfo == 0.9 16:55:22 INFO - mozleak == 0.1 16:55:22 INFO - mozlog == 3.4 16:55:22 INFO - moznetwork == 0.27 16:55:22 INFO - mozprocess == 0.23 16:55:22 INFO - mozprofile == 0.28 16:55:22 INFO - mozrunner == 6.12 16:55:22 INFO - mozscreenshot == 0.1 16:55:22 INFO - mozsystemmonitor == 0.3 16:55:22 INFO - moztest == 0.7 16:55:22 INFO - mozversion == 1.4 16:55:22 INFO - psutil == 3.1.1 16:55:22 INFO - requests == 1.2.3 16:55:22 INFO - wsgiref == 0.1.2 16:55:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55: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')]} 16:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55: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')]} 16:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:22 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 16:55:22 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 16:55:22 INFO - Using env: {'DISPLAY': ':0', 16:55:22 INFO - 'HOME': '/home/cltbld', 16:55:22 INFO - 'LANG': 'en_US.UTF-8', 16:55:22 INFO - 'LANGUAGE': 'en_US:en', 16:55:22 INFO - 'LOGNAME': 'cltbld', 16:55:22 INFO - 'MAIL': '/var/mail/cltbld', 16:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:22 INFO - 'MOZ_NO_REMOTE': '1', 16:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:22 INFO - 'NO_EM_RESTART': '1', 16:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:22 INFO - 'PWD': '/builds/slave/test', 16:55:22 INFO - 'SHELL': '/bin/bash', 16:55:22 INFO - 'SHLVL': '1', 16:55:22 INFO - 'TERM': 'linux', 16:55:22 INFO - 'TMOUT': '86400', 16:55:22 INFO - 'USER': 'cltbld', 16:55:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:22 INFO - '_': '/tools/buildbot/bin/python'} 16:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 INFO - Cleaning up... 16:55:22 INFO - Return code: 0 16:55:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:55: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')]} 16:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55: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')]} 16:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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 0x7fba5cac7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', '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 16:55:22 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 16:55:22 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 16:55:22 INFO - Using env: {'DISPLAY': ':0', 16:55:22 INFO - 'HOME': '/home/cltbld', 16:55:22 INFO - 'LANG': 'en_US.UTF-8', 16:55:22 INFO - 'LANGUAGE': 'en_US:en', 16:55:22 INFO - 'LOGNAME': 'cltbld', 16:55:22 INFO - 'MAIL': '/var/mail/cltbld', 16:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:22 INFO - 'MOZ_NO_REMOTE': '1', 16:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:22 INFO - 'NO_EM_RESTART': '1', 16:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:22 INFO - 'PWD': '/builds/slave/test', 16:55:22 INFO - 'SHELL': '/bin/bash', 16:55:22 INFO - 'SHLVL': '1', 16:55:22 INFO - 'TERM': 'linux', 16:55:22 INFO - 'TMOUT': '86400', 16:55:22 INFO - 'USER': 'cltbld', 16:55:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:22 INFO - '_': '/tools/buildbot/bin/python'} 16:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:55:22 INFO - Cleaning up... 16:55:22 INFO - Return code: 0 16:55:22 INFO - Running post-action listener: _resource_record_post_action 16:55:22 INFO - Running post-action listener: _start_resource_monitoring 16:55:22 INFO - Starting resource monitoring. 16:55:22 INFO - [mozharness: 2016-09-19 23:55:22.830716Z] Finished create-virtualenv step (success) 16:55:22 INFO - [mozharness: 2016-09-19 23:55:22.831310Z] Running install step. 16:55:22 INFO - Running pre-action listener: _resource_record_pre_action 16:55:22 INFO - Running main action method: install 16:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:23 INFO - Reading from file tmpfile_stdout 16:55:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:55:23 INFO - Reading from file tmpfile_stdout 16:55:23 INFO - Output received: 16:55:23 INFO - Usage: mozinstall [options] installer 16:55:23 INFO - Options: 16:55:23 INFO - -h, --help show this help message and exit 16:55:23 INFO - -d DEST, --destination=DEST 16:55:23 INFO - Directory to install application into. [default: 16:55:23 INFO - "/builds/slave/test"] 16:55:23 INFO - --app=APP Application being installed. [default: firefox] 16:55:23 INFO - mkdir: /builds/slave/test/build/application 16:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:55:39 INFO - Reading from file tmpfile_stdout 16:55:39 INFO - Output received: 16:55:39 INFO - /builds/slave/test/build/application/firefox/firefox 16:55:39 INFO - Running post-action listener: _resource_record_post_action 16:55:39 INFO - [mozharness: 2016-09-19 23:55:39.167703Z] Finished install step (success) 16:55:39 INFO - [mozharness: 2016-09-19 23:55:39.167949Z] Running run-tests step. 16:55:39 INFO - Running pre-action listener: _resource_record_pre_action 16:55:39 INFO - Running main action method: run_tests 16:55:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:55:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:55:39 INFO - Python 2.7.3 16:55:39 INFO - Return code: 0 16:55:39 INFO - grabbing minidump binary from tooltool 16:55: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')]} 16:55:39 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 0x269ef60>, '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 0x269fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a01d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:55:39 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 16:55:39 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 16:55:39 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 16:55:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:55:39 INFO - Return code: 0 16:55:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:55:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:55:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:55:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:55:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:55:39 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/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 16:55:39 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/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 16:55:39 INFO - Using env: {'DISPLAY': ':0', 16:55:39 INFO - 'HOME': '/home/cltbld', 16:55:39 INFO - 'LANG': 'en_US.UTF-8', 16:55:39 INFO - 'LANGUAGE': 'en_US:en', 16:55:39 INFO - 'LOGNAME': 'cltbld', 16:55:39 INFO - 'MAIL': '/var/mail/cltbld', 16:55:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:55:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:39 INFO - 'MOZ_NO_REMOTE': '1', 16:55:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:39 INFO - 'NO_EM_RESTART': '1', 16:55:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:39 INFO - 'PWD': '/builds/slave/test', 16:55:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:55:39 INFO - 'SHELL': '/bin/bash', 16:55:39 INFO - 'SHLVL': '1', 16:55:39 INFO - 'TERM': 'linux', 16:55:39 INFO - 'TMOUT': '86400', 16:55:39 INFO - 'USER': 'cltbld', 16:55:39 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650', 16:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:39 INFO - '_': '/tools/buildbot/bin/python'} 16:55:39 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/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 16:55:39 INFO - mozversion application_buildid: 20160919155316 16:55:39 INFO - mozversion application_changeset: c9971be9e98150ef99d4ef80c6f800ec5915b1ac 16:55:39 INFO - mozversion application_display_name: Nightly 16:55:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:55:39 INFO - mozversion application_name: Firefox 16:55:39 INFO - mozversion application_remotingname: firefox 16:55:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:55:39 INFO - mozversion application_vendor: Mozilla 16:55:39 INFO - mozversion application_version: 52.0a1 16:55:39 INFO - mozversion platform_buildid: 20160919155316 16:55:39 INFO - mozversion platform_changeset: c9971be9e98150ef99d4ef80c6f800ec5915b1ac 16:55:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:55:39 INFO - mozversion platform_version: 52.0a1 16:55:39 INFO - using testdate: 1474329339 16:55:39 INFO - actual date: 1474329339 16:55:39 INFO - starting webserver on 'localhost:52819' 16:55:39 INFO - SUITE-START | Running 2 tests 16:55:39 INFO - TEST-START | dromaeo_css 16:55:39 INFO - operating with platform_type : linux_ 16:55:39 INFO - Initialising browser for dromaeo_css test... 16:55:39 INFO - TEST-INFO | started process 29856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1YaGgc/profile http://localhost:52819/getInfo.html) 16:55:43 INFO - PROCESS | 29856 | __metrics Screen width/height:1600/1200 16:55:43 INFO - PROCESS | 29856 | colorDepth:24 16:55:43 INFO - PROCESS | 29856 | Browser inner width/height: 1024/697 16:55:43 INFO - PROCESS | 29856 | __metrics 16:55:44 INFO - PROCESS | 29856 | 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" 16:55:44 INFO - PROCESS | 29856 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:55:45 INFO - PROCESS | 29856 | console.error: PushService: 16:55:45 INFO - PROCESS | 29856 | stateChangeProcessEnqueue: Error transitioning state 16:55:45 INFO - PROCESS | 29856 | UnknownError 16:55:45 INFO - TEST-INFO | 29856: exit 0 16:55:45 INFO - Browser initialized. 16:55:45 INFO - Running cycle 1/1 for dromaeo_css test... 16:55:45 INFO - TEST-INFO | started process 29940 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1YaGgc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:55:57 INFO - PROCESS | 29940 | RSS: Main: 174485504 16:55:57 INFO - PROCESS | 29940 | 16:56:07 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:56:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:56:29 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:56:31 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:56:48 INFO - PROCESS | 29940 | 1474329408803 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] 16:56:50 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 16:56:52 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 16:56:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:56:59 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:57:08 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 16:57:16 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:57:19 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 16:57:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:57:34 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:57:37 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 16:57:42 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 16:57:47 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.20004 seconds. 16:57:53 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 16:57:53 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 16:58:00 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 16:58:08 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:58:10 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:58:17 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:58:22 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 16:58:36 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.10003 seconds. 16:58:38 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:58:42 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:59:02 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:59:02 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52819/tests/dromaeo/cssquery-ext.html) 16:59:03 INFO - PROCESS | 29940 | RSS: Main: 267108352 16:59:03 INFO - PROCESS | 29940 | 16:59:06 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:59:13 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:59:15 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:59:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:59:22 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:59:32 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:59:35 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:59:52 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:59:56 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:00:08 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:00:10 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:00:22 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:00:24 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:00:39 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:00:41 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 17:00:43 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:00:45 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:00:54 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:00:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:01:04 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:01:07 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:01:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:01:29 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:01:51 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:01:55 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:01:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:01:59 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:02:01 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:02:03 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 17:02:06 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-ext.html (next: http://localhost:52819/tests/dromaeo/cssquery-jquery.html) 17:02:06 INFO - PROCESS | 29940 | RSS: Main: 268566528 17:02:06 INFO - PROCESS | 29940 | 17:02:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:02:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:02:32 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:02:35 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:02:39 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:02:41 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:02:43 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:02:52 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:02:53 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:02:59 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:03:05 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:03:09 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 17:03:12 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:03:15 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:03:17 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:03:19 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:03:34 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:03:40 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 17:03:51 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 17:04:02 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 17:04:17 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 17:04:18 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 17:04:26 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:04:36 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:04:38 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:04:48 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:04:54 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:04:56 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:05:08 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52819/tests/dromaeo/cssquery-mootools.html) 17:05:09 INFO - PROCESS | 29940 | RSS: Main: 255041536 17:05:09 INFO - PROCESS | 29940 | 17:05:12 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 17:05:24 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:05:35 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:05:36 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 17:05:49 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:05:59 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 17:06:10 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 17:06:15 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 17:06:18 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:06:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:06:22 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:06:34 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 17:06:38 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:07:06 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:07:09 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:07:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:07:25 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 17:07:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:07:29 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:07:31 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:07:35 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:07:39 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:07:47 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:07:50 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:07:54 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:08:11 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52819/tests/dromaeo/cssquery-prototype.html) 17:08:12 INFO - PROCESS | 29940 | RSS: Main: 236228608 17:08:12 INFO - PROCESS | 29940 | 17:08:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:08:59 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:09:02 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:09:12 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:09:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:09:28 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:09:30 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:09:36 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:09:38 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:09:40 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:09:56 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 17:10:00 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:10:02 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 17:10:07 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:10:08 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:10:09 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 2.00004 seconds. 17:10:12 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 17:10:13 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 2.0167 seconds. 17:10:18 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:10:20 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 17:10:23 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:10:27 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:10:31 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 17:10:36 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:10:38 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:10:39 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:10:49 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 17:10:51 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:10:53 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:10:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:11:13 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 17:11:15 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52819/tests/dromaeo/cssquery-yui.html) 17:11:16 INFO - PROCESS | 29940 | RSS: Main: 218869760 17:11:16 INFO - PROCESS | 29940 | 17:11:19 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.03332 seconds. 17:11:24 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:11:28 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:11:32 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 17:11:57 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:12:30 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 17:12:35 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 17:13:03 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 17:13:04 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.01657 seconds. 17:13:06 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:13:22 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:13:26 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:13:28 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:13:32 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:13:34 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:13:46 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 17:14:01 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 17:14:11 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 17:14:17 INFO - PROCESS | 29940 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 17:14:19 INFO - PROCESS | 29940 | Cycle 1(1): loaded http://localhost:52819/tests/dromaeo/cssquery-yui.html (next: http://localhost:52819/tests/dromaeo/cssquery-dojo.html) 17:14:19 INFO - PROCESS | 29940 | RSS: Main: 299556864 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | __start_tp_report 17:14:19 INFO - PROCESS | 29940 | _x_x_mozilla_page_load 17:14:19 INFO - PROCESS | 29940 | _x_x_mozilla_page_load_details 17:14:19 INFO - PROCESS | 29940 | |i|pagename|runs| 17:14:19 INFO - PROCESS | 29940 | |0;dojo.html;2524.2931979045866;2545.3763827862176;2575.6265341525477;2584.4185058361873;2601.8699065046753;4645.69802962808;4700.130475861968;4708.128996135717;4713.929291060633;4719.197736384815;4739.383880095591;4761.8809529761775;4765.690230135047;4777.044591081789;4788.688735232207;4277.165952639237;4350.54319296474;4361.454818147731;4406.118776244748;4443.577860103301;4315.050688848464;4357.281048626973;4389.648828093761;4407.471103467572;4451.043025749464;4366.716163449366;4444.022315090675;4451.62161216297;4470.82116715331;4472.932906006413;4379.1022840317655;4400.669949753781;4443.533428989974;4467.910641787179;4483.977580112111;4285.421468101828;4287.742735435852;4320.179165958458;4343.84796532123;4396.3625274335045;4672.4393072831135;4678.812847486097;4747.35910652061;4778.402699662543;4817.710937343771;5007.474215207387;5049.141645920203;5053.620978426633;5155.536001759822;5175.560077393426;5107.7446127693465;5116.027954688598;5120.487951204887;5129.717865517395;5179.067767801795;4563.612092972068;4614.515475875052;4746.999999999965;4767.594754445911;4824.903501930012;5048.545630893237;5087.949120508822;5108.2848401223855;5113.488651134857;5172.818951336685;4827.348307978378;4858.1012512685265;4870.366852309177;4900.583450406755;4931.654784165074;4869.683470574401;4917.0903382874285;4965.826196083192;4974.552290293891;5039.294498770175;4899.412070551485;4915.8033678652455;4938.777755001033;4953.777080031336;5010.323606313174;4910.21436570147;4918.483559226302;4921.7785199666105;4924.8768780780765;4989.575886049726;4181.289180839264;4210.221109094828;4224.809883555247;4231.640310573574;4289.270823959934;3693.4459831025024;3733.197362567061;3747.063234191452;3776.169242766587;3788.6590206881515;4386.8025938832825;4387.166438376698;4390.187815254124;4432.601065904019;4441.911161776747;5131.358580177478;5135.768890399941;5145.4854514549;5148.279240906276;5200.6619569727845;4276.358546218092;4302.182585308781;4319.092990472036;4343.457067866517;4372.868813935587;4564.109998550317;4566;4594.540545945379;4622.029373831533;4624.237000894815;4747.905041899212;4749.857504274877;4750.263709125091;4774.259989701602;4814.229723244404;4913.926291105565;4989.600831933364;5037.546620803999;5055.898882022412;5073.34046573943;4721.929171062506;4730.385049943623;4731.645126615449;4778.068276686013;4799.688020278811;4970.055689418999;4972.801087956586;4980.800767969241;4990.4011518618;5033.823816166563;4972.403311602631;5036.798528058836;5044.243363495505;5050.393952725696;5115.641905066758;4912.508749125059;4945;4977.402711674622;4988.4263309719845;5052.59579233653;4978.377702787152;4984.551390374883;4985.052839960396;4986.675866068694;5050.292958985819;4723.197056500472;4911.435184953776;4916.410030796327;4959.479254678351;5009.173486374707;4856.417229932431;4938.728369939688;4968.8757781055765;4996.7001979881325;5059.494050594911;4866.367372241586;4880.267959806058;4897.632677549269;4899.093667671492;4950.851474455772 17:14:19 INFO - PROCESS | 29940 | |1;ext.html;545.7680485793538;547.5592148320603;560.9551235901129;562.0781917655047;564.286177984849;27154.26103560483;37686.43470347947;39356;39396.80301598495;43432.13135737283;40004.199916001664;40442.797786011026;40493.392599110965;40522.18955620886;40523.59476405235;7613.61931903404;7763.495372800779;7766.805829854271;7771.727989520368;7774.689012439496;7609.733659321925;7698.769036928873;7699.230076992312;7701.576413297266;7705.922940770577;7648.158702542716;7703.1526532081425;7706.229377062305;7706.845863082735;7710.653020614058;7679.500832445901;7715.421343399212;7716.884246736304;7724.30481256684;7726.381889448859;7551.131619863731;7617.124030736481;7620.618969051525;7622.428317876182;7638.5798781067015;37149;37392.065198369986;37405.62594374049;37490.62509374926;37583.624163758286;2913.1988703106604;2952.867120979561;2966.2287805170595;3000.8799648014055;3041.406925649499;27642.308942276402;28199.85900070507;28228.71771282302;30465;30469;2961.5557666350005;3015.909522714322;3021.9848900755574;3039.6352437707396;3040.239940014996;249240;251551;251705;252053;253235;53483.46516534863;53862.46137538574;53913.73043134838;53913.73043134838;54017.189742153896;32125.678743212735;32980.1754956128;33130.17174570655;34775.4783678245;57594.7120264396;14909.092726918363;15065.870059745563;15067.623309417353;15080.813282074037;15082.868784841181;11473.082153427706;11549.076073914066;11563.537458501565;11582.6525204244;11583.478743456564;17626.91186544085;17702.203400846993;17740.822591774177;17749.467515974542;17828.771403574552;6558.442532384705;6585.275619681831;6684.565503242274;6828.688086278151;6879.724811007603;707.179014038852;900.5362238447204;905.6015353244551;924.3760461688333;956.9760755981017;25579.23262302134;27255.86372068127;27283.45433091327;27508.312292192855;27947.30131746682;726.934575888162;819.6598411659095;850.8766228896775;867.314821291173;966.0773960867355;14340.856591433952;14725;14855.48005819791;15088.773668394982;15092.1699306537;336163;338150;338827;339126;339378;47463.5253647466;48264.758676206395;48301.275480867815;48393;48405;1077.21363404715;1100.6753007862699;1110.1618278199908;1164.2025212729307;1193.7015746063485;25552.616710749724;28629.28426789347;29182.27044323909;29442.558361624164;29744.553831692974;39226;39563.604363955994;39691.40462893001;39694.60305396909;39706.40440393339;285747;286626;286759;287165;287506;23930.760692392854;24873.378165545284;25090.62364064577;25098.37254068663;26048.21855344343;40045;40203.39694904637;40641.796791015855;40772.79613601913;41497.37753933633;34698;34796;34840.47739283862;35039.64960350364;35081.298374032885;34342.48486272658;34577.30845383128;34590.82704586461;34999.30001400009;35034;33965.49051764176;34290.314193716484;34871.82564087163;35032;35087.64912350844;29215;31828.5225721619;31944.36111277808;31991.84004079965;32166.67833321637;24677.38306542279;24734.38164045913;24824;24841.727439033144;24844.751552484242 17:14:19 INFO - PROCESS | 29940 | |2;jquery.html;344.6123111499563;372.51200926785896;374.5393166405322;375.7332294071209;392.3604524624861;12073.154879158463;13090.34548272585;13810.792838107436;16209.675806483863;16888.07115608644;16324.18379081044;16647.91676041618;16789.076600786982;16903;16913.323467061302;4641.907161856752;5096.133657278253;5114.693118412889;5125.666831655949;5127.230915362688;3319.2199833039217;5051.570616497602;5131.974340128294;5134.948650513503;5139.743012849362;3527.576690797108;3530.823458827061;3570.5184896496944;5958.195643588125;6038.607490513103;5626.971865140689;6097.26832780067;6102.572819902609;6103.176071230371;6107.4808641265545;5215.791368345262;5225.581953443715;5233.528982391603;5241.764120614582;5242.18746094356;11504.597339093176;11553;11563.479643416067;18648;19681.31115410954;1683.3939781678587;1965.2924947019062;2174.3259589527297;2190.627593309141;2244.461329280961;8509.489430634181;8548.572571371407;8706.782330441727;8989.640414383417;9059.682911098145;1705.1303835044216;1818.7905043146293;1846.2984066054814;1853.6848735714962;1900.8574356923148;47366.76316618429;65694;69112.30887691109;80485;82224.58887705523;15190.063154528721;16174.544291013864;16249.675006499803;16262.186890655657;16276.51170464888;28444.573331400046;28512.85743571269;28587.428251434856;28626;28733.56899646507;8514.276286515591;8662.523561204069;8776.078511756301;8977.865332020025;9029.548522573845;7615.069719215684;7772.533647981139;7874.370050395954;8016.679332826621;8101.837963240702;8394.664213431395;8499.830003399897;8581.828363432696;8651.6971905983;8714.912850871537;2798.5802129680305;3108.2384815719843;3184.2039490127468;3241.9837900810817;3371.3425881953276;744.155383639573;780.9765707028798;792.4175730837829;836.3560058754846;851.438050886412;16287.592810179603;16292.592685182964;16402.179891005402;16488.917555412147;17039.14804259782;779.6413649721078;792.841431713648;809.0938149272852;824.3940703582862;856.3320609924269;6114.633122012693;9265.2124569411;9453.716388508356;9569.808603828023;9663.758406039764;374674;385326;385438;388130;388539;76291;77115.61442192753;78730.60634696904;79108;79781;874.3355050161796;883.6421249394006;888.138505649519;892.5492626223715;895.7088946092676;13887.722245555235;14909.390856599835;15131;15152.242387880342;15494.767578486557;38056.619433806416;38475.230495389376;38495.80752096222;39411;40034.39948400718;47713.52286477091;51000;51144;51181;51825.74087129691;4810.148092984296;7521.699132034876;7596.08846938371;7654.5839019781515;7772.37820974332;9348.298877584344;10998.615048473264;11148.665540033811;11391.772164556829;11517.53929842797;7284.198738138906;8774.736757897273;9597.664081757384;9669.806603868024;9684.32209745311;9864.5560949756;9974.301798874008;9976.900230997597;10012.44931528773;10297.485125743533;9914.30599858003;10016.899831001889;10096.596136154472;10177.542010609688;10663.946680266548;7172.318629730167;7394.9277354784335;7436.851262974818;7509.962450187714;7887.211278872296;9532;9616.08647178517;9824.361416507903;10167.339122956984;10704.678859634223 17:14:19 INFO - PROCESS | 29940 | |3;mootools.html;1344.2875276103666;1364.2769332253904;1392.5752645443142;1606.2129556517295;1663.7837081179437;6734.225564060134;7031.94520821877;7281.635918204089;7861.056673199217;7863.724769633057;7422;7735.497192682486;7750.341220996208;7978.321842643368;8014.5591992440395;2194.242986169773;2211.0602993727603;2211.7124773779387;2222.9110835566557;2225.899834507443;1851.685213513706;1918.1272521002911;2102.7686954435;2113.2814842953394;2152.0100753653337;2137.4976880433087;2179.923702670407;2188.059134572133;2194.681771143183;2196.286206982729;2148.4306658735527;2156.051337411534;2189.6168170570245;2193;2193.144673577306;2122.4057263966115;2147.538279269949;2151.5262045498885;2162.6915715991913;2164.599549083409;7858.292753652198;7920.683172673086;7921.247481489249;7921.762347129596;7922.920770792276;4354.455693038371;4516.096780643851;4516.570925762047;4528.909421811578;4662.254039353687;2800.355918138819;2860.2420358604986;2910.9272268193254;2933.603963464948;2975.4346674131843;927.5501381829807;949.6296444386695;955.2358113509165;969.8351280282369;980.3039841712392;9605.18355939746;9870.753231169207;9871.950640246825;10106;10133.949330253376;3942.6845852331744;4011.8796436106963;4054.39184122384;4132.2561938851295;4137.855175068858;8136.918630813734;8137.877931831027;8161.63272652732;8167.101618821925;8172.42793004484;4452.799624016862;4454.532274111237;4462.553744625511;4463.910721785546;4465.26323156682;4089.8773036808348;4184.811683474251;4186.748795072305;4191.350340697197;4195.643370313496;3369.578802649669;3398.2693720850134;3447.379471695069;3470.236547959445;3567.5718913729997;951.4291425144858;982.8771403574553;993.7813680990171;999.8301986675607;1012.422918936214;707.9362857342863;713.3472872321768;721.0590179815287;721.9784005632076;741.1921006103265;4591.95408045922;4672.229082201398;4690.530946905282;4788.281757736368;4852.490488498657;763.969441222356;775.3603277296253;789.5933594198992;803.2890891561011;826.3967303868089;3113.937721245608;3197.328561002169;3199.9040028799172;3220.388126256004;3227.5158726191257;9557.283203759607;9809.264305177141;9887.406755594688;9894.406335619742;9895.059969302905;9717;9718.514074296258;9722;9750.707478775648;9758.316917815826;6688.230853451818;6937.583744975217;6973.267806880306;7130.607816570035;7225;4923.975380123076;4946.629002824874;4991.625628077982;5002.749862506933;5205.45342739007;8477.533735644598;8797.38418310712;8936.821263574822;8965.282777377925;9051.547422628973;9114.954425227832;9115.726528204164;9131.08689131108;9144.451332920045;9163.49600771964;6718.093057437183;6875.140607424072;6876.931230687829;6878.312168783082;6878.312168783082;7205.207427182901;7450.96274518624;7496.887546686695;7503.474756766974;7507.286807753255;5400.6412546867095;6372.299047104722;6658.400743933069;6704.161979752531;6803.013563033432;6535.44448721864;6543.345665433419;6686.398224159849;6726.822806008831;6776.119104516381;5900.350961394329;5968.6717230551;5979.970100149471;5989.7304621293015;5996.610220335664;4077.531083925268;4123.8556650517085;4135.31767258399;4165.875023749292;4254.042840360894;4070.9592904070582;4112.074783173762;4117.835286588623;4191.601797829201;4195.1819395217635 17:14:19 INFO - PROCESS | 29940 | |4;prototype.html;293.9309262323355;300.6482415573779;307.33922067554755;307.5095223119124;310.5543545012905;4862.683925544841;4885.560299573038;4902.975485122578;4908.9936563004585;4926.753662316888;4558.680892337537;4605.953940460595;4754.215554433515;4774.331593576893;4845.466998630148;3798.9240215195678;3804.790736509498;3809.657130858222;3816.7709937403706;3821.331267028273;3806.7525610835346;3812;3846.7499612525235;3847.1728578355646;3849.1531862990096;3579.6062433132333;3648.5256916600806;3794.146317078663;3804.3641234269253;3837.3882969153024;3807.4098514730263;3807.447920051605;3810.90472738181;3810.9519882032387;3827.8086095695103;3379.4761811919193;3381.2392211752403;3382.1375549235036;3385.306012267479;3413.1467133216697;4576.977115114437;4784.952150478485;4793.544613261734;4797.928031079537;4805.591524720403;4336.653067754603;4343.67422443318;4391.824327026915;4399.252127138362;4410.448693913261;5079.314292570492;5092.974535127338;5101.82143624975;5121.155009423441;5165.741712914377;3706.703463722896;3717.070732316921;3724.5344331958504;3742.981285093584;3745.793981331026;5044.47033061527;5301.3373328333955;5329.360476742816;5349.732513374354;5396.487333703292;3661.5972243053243;3673.9632603674154;3674.2651469706166;3679.1537946272506;3686.594474607791;3730.8694195703565;3737.6449237322413;3738.27103714773;3755.8122093895195;3765.0963768695865;4231.894202644897;4305.686765536479;4349.999500114991;4352.6735494837385;4356.194104090753;5088.62607096082;5111.616628752859;5118.59051275897;5123.333966584395;5127.97436012825;1285.1967520299813;1299.6945717756319;1309.4892991733232;1310.4299629660936;1314.7304802515462;828.130463013833;832.1595188859175;850.7447765670274;857.0829212742306;897.4884315939852;4463.709858859229;4478.865634030919;4481.596656300883;4490.685652004328;4500.862090507048;5233.816816411408;5250.159974404077;5257.973710131425;5268.92096618551;5277.736113194325;4402;4434.262948207172;4495.190865644216;4524.366588677588;4531.909361812811;2784.7215278472395;2814.5637426198973;2819.3656427303695;2822.379076603144;2826.915192544227;4916.2625606158645;4917.360743103373;4966.15575352191;4970.65205435616;4976.278439626234;5109.518239710516;5323.1635085895305;5358.892822143535;5424.728763561806;5462.453754624506;4964.361760618987;4978.377702787152;4983.875403114878;4985.925211121908;4987.675801072846;5334.279872217201;5335.1730481775385;5343.7709326854265;5370.704611246419;5373.758180881948;5069.453816585977;5192.169252919514;5251.868703282295;5252.65857719247;5263.236830659456;4995.375578052744;5002.899942001212;5016.347874776256;5022;5022.522860328263;3743.1016556026902;3761.097336639242;3767.434884767307;3768.1898391845884;3801.923961520699;5168.198929165986;5289.8677533060445;5322.669332666834;5331.360236771612;5431.6740995540395;5147.2279158126585;5159.587233021274;5169.405518365285;5170.8965820684125;5175.741212939413;5155.355580552431;5174.404943431554;5204.505571970657;5216.843494695166;5227.660202086852;4896.4124305083615;4986.601071914166;5044.915343201229;5055.545000949932;5061.240813877947;4930.7534623267975;5066.873328166826;5115.232715092766;5200.141976573975;5218.504242096995;5047.4952504749235;5072.5688316493515;5081.822136225214;5082.847514574569;5105.617078719037 17:14:19 INFO - PROCESS | 29940 | |5;yui.html;581.4852309733145;597.5936363272975;619.9535034872383;642.2004604267687;654.6661202786578;12619.179753316028;13492.595222143327;13576.389062492186;13701.3834377453;13747.862521374784;13545.119567228123;14252.71494570108;14362.928185359058;14903.552893413187;14906.925465372686;739.6560599321315;762.3481922955876;765.8621448139332;766.2490759056128;766.4481573267238;595.6902410746409;605.9030555111183;608.2002167150206;616.029753138806;762.0778857582332;656.0520048529876;764.9005629268187;795.4431897671624;797.3740613618311;802.4342838298985;783.9451238413313;790.549386849496;791.734768852438;792.5165648954134;796.4584082823677;579.9942000579982;580.1703563903616;580.4108829538022;580.6022874331097;580.7938181945433;10576.153907687367;11036;13305.135166214164;13993.580192594238;14012.22932738699;11749.4712737927;12492.25046497213;12557.121001529897;12806.231626102464;12974.286414247212;10733.463326833706;10764.02934463969;10904.50929708165;10913.890861091368;11037.944810275978;3153.4008538377634;3157.431662300785;3170.1440611035123;3230.78999865008;3261.2010057535817;68031.65984170097;69384;70116.298837012;70315.2968470314;71357;9606;9661.275404344562;9883.555240014217;9976.610291471728;10001.749956251006;17834.732479012826;17856.642867142844;17864.106794660216;17879.821201788076;17906.373276935246;6521.739130434729;6525.1517302750335;6610.8347291318105;6667.533272670964;6671.699773510204;3599.478075679064;3616.511770910946;3682.576503702055;3691.686206672463;3702.129999450127;2758.7930905182193;2848.900288489893;2858.7855910806775;2864.64191976003;2875.7555607773575;480.79566184371504;482.9275608658659;490.2939766735889;494.03662857428156;498.9276554899519;431.8985038515946;458.94442781602845;467.07985269019974;472.17840956735034;484.1213198045536;6970.407515361251;7134.02246208711;7259.673314700851;7385.724828259119;7647.961760191163;529.4811085136588;538.4265756968815;540.9702466364388;543.0957455836068;559.8068666310116;3747.1194269346684;3787.753796003251;3806.4480650306105;3899;3914.236723838881;78198.60900695574;79711.20288797037;81439.59280203561;83343;106409.46795265975;64938.025929611096;74386.6280668604;76178.61910690411;77590.61204693941;77823;53444.19833702497;70035.64982175057;72543;76739.61630191813;77244;7548.452567223816;8256.091829898587;8317.625706843086;8441.915580844114;8533.35999800005;42547;42572.57427425686;43154.35268470913;43227.78386108049;44641;58196.4180358191;62503;63258.68370658117;63473;65030.34969650243;9700.078492543196;10036.146927510952;10385.896141038493;10427.113695335982;10549.683509494727;10997.450127493754;11359.318440893572;11458.94270528642;12252.877471225174;12354.876451235732;10282.537285822305;10316.742081447723;10402.323848949794;11051.778964420828;11521;10516.842247366449;10666.093382062612;10687.47313386596;11026.228164028442;11274.154438417316;9471.47906865129;9683.176929961031;9946.254030947563;10111.938246484013;10343.793124137324;8511.872321915052;8554.871676924975;8619.1811777881;8678.913210867811;8945.955270223607;8780.692675756318;8795.47227166372;8810.86783698258;9149.417981009925;9545.952270238604 17:14:19 INFO - PROCESS | 29940 | __end_tp_report 17:14:19 INFO - PROCESS | 29940 | __start_cc_report 17:14:19 INFO - PROCESS | 29940 | _x_x_mozilla_cycle_collect,1090 17:14:19 INFO - PROCESS | 29940 | __end_cc_report 17:14:19 INFO - PROCESS | 29940 | __startTimestamp1474330459647__endTimestamp 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | ------- Summary: start ------- 17:14:19 INFO - PROCESS | 29940 | Number of tests: 6 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#0] dojo.html Cycles:165 Average:4673.29 Median:4799.69 stddev:493.78 (10.3%) stddev-sans-first:465.63 17:14:19 INFO - PROCESS | 29940 | Values: 2524.3 2545.4 2575.6 2584.4 2601.9 4645.7 4700.1 4708.1 4713.9 4719.2 4739.4 4761.9 4765.7 4777.0 4788.7 4277.2 4350.5 4361.5 4406.1 4443.6 4315.1 4357.3 4389.6 4407.5 4451.0 4366.7 4444.0 4451.6 4470.8 4472.9 4379.1 4400.7 4443.5 4467.9 4484.0 4285.4 4287.7 4320.2 4343.8 4396.4 4672.4 4678.8 4747.4 4778.4 4817.7 5007.5 5049.1 5053.6 5155.5 5175.6 5107.7 5116.0 5120.5 5129.7 5179.1 4563.6 4614.5 4747.0 4767.6 4824.9 5048.5 5087.9 5108.3 5113.5 5172.8 4827.3 4858.1 4870.4 4900.6 4931.7 4869.7 4917.1 4965.8 4974.6 5039.3 4899.4 4915.8 4938.8 4953.8 5010.3 4910.2 4918.5 4921.8 4924.9 4989.6 4181.3 4210.2 4224.8 4231.6 4289.3 3693.4 3733.2 3747.1 3776.2 3788.7 4386.8 4387.2 4390.2 4432.6 4441.9 5131.4 5135.8 5145.5 5148.3 5200.7 4276.4 4302.2 4319.1 4343.5 4372.9 4564.1 4566.0 4594.5 4622.0 4624.2 4747.9 4749.9 4750.3 4774.3 4814.2 4913.9 4989.6 5037.5 5055.9 5073.3 4721.9 4730.4 4731.6 4778.1 4799.7 4970.1 4972.8 4980.8 4990.4 5033.8 4972.4 5036.8 5044.2 5050.4 5115.6 4912.5 4945.0 4977.4 4988.4 5052.6 4978.4 4984.6 4985.1 4986.7 5050.3 4723.2 4911.4 4916.4 4959.5 5009.2 4856.4 4938.7 4968.9 4996.7 5059.5 4866.4 4880.3 4897.6 4899.1 4950.9 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#1] ext.html Cycles:180 Average:44332.57 Median:34785.74 stddev:77166.05 (221.8%) stddev-sans-first:77312.49 17:14:19 INFO - PROCESS | 29940 | Values: 545.8 547.6 561.0 562.1 564.3 27154.3 37686.4 39356.0 39396.8 43432.1 40004.2 40442.8 40493.4 40522.2 40523.6 7613.6 7763.5 7766.8 7771.7 7774.7 7609.7 7698.8 7699.2 7701.6 7705.9 7648.2 7703.2 7706.2 7706.8 7710.7 7679.5 7715.4 7716.9 7724.3 7726.4 7551.1 7617.1 7620.6 7622.4 7638.6 37149.0 37392.1 37405.6 37490.6 37583.6 2913.2 2952.9 2966.2 3000.9 3041.4 27642.3 28199.9 28228.7 30465.0 30469.0 2961.6 3015.9 3022.0 3039.6 3040.2 249240.0 251551.0 251705.0 252053.0 253235.0 53483.5 53862.5 53913.7 53913.7 54017.2 32125.7 32980.2 33130.2 34775.5 57594.7 14909.1 15065.9 15067.6 15080.8 15082.9 11473.1 11549.1 11563.5 11582.7 11583.5 17626.9 17702.2 17740.8 17749.5 17828.8 6558.4 6585.3 6684.6 6828.7 6879.7 707.2 900.5 905.6 924.4 957.0 25579.2 27255.9 27283.5 27508.3 27947.3 726.9 819.7 850.9 867.3 966.1 14340.9 14725.0 14855.5 15088.8 15092.2 336163.0 338150.0 338827.0 339126.0 339378.0 47463.5 48264.8 48301.3 48393.0 48405.0 1077.2 1100.7 1110.2 1164.2 1193.7 25552.6 28629.3 29182.3 29442.6 29744.6 39226.0 39563.6 39691.4 39694.6 39706.4 285747.0 286626.0 286759.0 287165.0 287506.0 23930.8 24873.4 25090.6 25098.4 26048.2 40045.0 40203.4 40641.8 40772.8 41497.4 34698.0 34796.0 34840.5 35039.6 35081.3 34342.5 34577.3 34590.8 34999.3 35034.0 33965.5 34290.3 34871.8 35032.0 35087.6 29215.0 31828.5 31944.4 31991.8 32166.7 24677.4 24734.4 24824.0 24841.7 24844.8 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#2] jquery.html Cycles:180 Average:24601.81 Median:28153.33 stddev:63541.51 (225.7%) stddev-sans-first:63693.66 17:14:19 INFO - PROCESS | 29940 | Values: 344.6 372.5 374.5 375.7 392.4 12073.2 13090.3 13810.8 16209.7 16888.1 16324.2 16647.9 16789.1 16903.0 16913.3 4641.9 5096.1 5114.7 5125.7 5127.2 3319.2 5051.6 5132.0 5134.9 5139.7 3527.6 3530.8 3570.5 5958.2 6038.6 5627.0 6097.3 6102.6 6103.2 6107.5 5215.8 5225.6 5233.5 5241.8 5242.2 11504.6 11553.0 11563.5 18648.0 19681.3 1683.4 1965.3 2174.3 2190.6 2244.5 8509.5 8548.6 8706.8 8989.6 9059.7 1705.1 1818.8 1846.3 1853.7 1900.9 47366.8 65694.0 69112.3 80485.0 82224.6 15190.1 16174.5 16249.7 16262.2 16276.5 28444.6 28512.9 28587.4 28626.0 28733.6 8514.3 8662.5 8776.1 8977.9 9029.5 7615.1 7772.5 7874.4 8016.7 8101.8 8394.7 8499.8 8581.8 8651.7 8714.9 2798.6 3108.2 3184.2 3242.0 3371.3 744.2 781.0 792.4 836.4 851.4 16287.6 16292.6 16402.2 16488.9 17039.1 779.6 792.8 809.1 824.4 856.3 6114.6 9265.2 9453.7 9569.8 9663.8 374674.0 385326.0 385438.0 388130.0 388539.0 76291.0 77115.6 78730.6 79108.0 79781.0 874.3 883.6 888.1 892.5 895.7 13887.7 14909.4 15131.0 15152.2 15494.8 38056.6 38475.2 38495.8 39411.0 40034.4 47713.5 51000.0 51144.0 51181.0 51825.7 4810.1 7521.7 7596.1 7654.6 7772.4 9348.3 10998.6 11148.7 11391.8 11517.5 7284.2 8774.7 9597.7 9669.8 9684.3 9864.6 9974.3 9976.9 10012.4 10297.5 9914.3 10016.9 10096.6 10177.5 10663.9 7172.3 7394.9 7436.9 7510.0 7887.2 9532.0 9616.1 9824.4 10167.3 10704.7 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#3] mootools.html Cycles:180 Average:4982.07 Median:5974.32 stddev:2837.32 (47.5%) stddev-sans-first:2832.11 17:14:19 INFO - PROCESS | 29940 | Values: 1344.3 1364.3 1392.6 1606.2 1663.8 6734.2 7031.9 7281.6 7861.1 7863.7 7422.0 7735.5 7750.3 7978.3 8014.6 2194.2 2211.1 2211.7 2222.9 2225.9 1851.7 1918.1 2102.8 2113.3 2152.0 2137.5 2179.9 2188.1 2194.7 2196.3 2148.4 2156.1 2189.6 2193.0 2193.1 2122.4 2147.5 2151.5 2162.7 2164.6 7858.3 7920.7 7921.2 7921.8 7922.9 4354.5 4516.1 4516.6 4528.9 4662.3 2800.4 2860.2 2910.9 2933.6 2975.4 927.6 949.6 955.2 969.8 980.3 9605.2 9870.8 9872.0 10106.0 10133.9 3942.7 4011.9 4054.4 4132.3 4137.9 8136.9 8137.9 8161.6 8167.1 8172.4 4452.8 4454.5 4462.6 4463.9 4465.3 4089.9 4184.8 4186.7 4191.4 4195.6 3369.6 3398.3 3447.4 3470.2 3567.6 951.4 982.9 993.8 999.8 1012.4 707.9 713.3 721.1 722.0 741.2 4592.0 4672.2 4690.5 4788.3 4852.5 764.0 775.4 789.6 803.3 826.4 3113.9 3197.3 3199.9 3220.4 3227.5 9557.3 9809.3 9887.4 9894.4 9895.1 9717.0 9718.5 9722.0 9750.7 9758.3 6688.2 6937.6 6973.3 7130.6 7225.0 4924.0 4946.6 4991.6 5002.7 5205.5 8477.5 8797.4 8936.8 8965.3 9051.5 9115.0 9115.7 9131.1 9144.5 9163.5 6718.1 6875.1 6876.9 6878.3 6878.3 7205.2 7451.0 7496.9 7503.5 7507.3 5400.6 6372.3 6658.4 6704.2 6803.0 6535.4 6543.3 6686.4 6726.8 6776.1 5900.4 5968.7 5980.0 5989.7 5996.6 4077.5 4123.9 4135.3 4165.9 4254.0 4071.0 4112.1 4117.8 4191.6 4195.2 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#4] prototype.html Cycles:180 Average:4256.83 Median:4874.12 stddev:1238.88 (25.4%) stddev-sans-first:1206.12 17:14:19 INFO - PROCESS | 29940 | Values: 293.9 300.6 307.3 307.5 310.6 4862.7 4885.6 4903.0 4909.0 4926.8 4558.7 4606.0 4754.2 4774.3 4845.5 3798.9 3804.8 3809.7 3816.8 3821.3 3806.8 3812.0 3846.7 3847.2 3849.2 3579.6 3648.5 3794.1 3804.4 3837.4 3807.4 3807.4 3810.9 3811.0 3827.8 3379.5 3381.2 3382.1 3385.3 3413.1 4577.0 4785.0 4793.5 4797.9 4805.6 4336.7 4343.7 4391.8 4399.3 4410.4 5079.3 5093.0 5101.8 5121.2 5165.7 3706.7 3717.1 3724.5 3743.0 3745.8 5044.5 5301.3 5329.4 5349.7 5396.5 3661.6 3674.0 3674.3 3679.2 3686.6 3730.9 3737.6 3738.3 3755.8 3765.1 4231.9 4305.7 4350.0 4352.7 4356.2 5088.6 5111.6 5118.6 5123.3 5128.0 1285.2 1299.7 1309.5 1310.4 1314.7 828.1 832.2 850.7 857.1 897.5 4463.7 4478.9 4481.6 4490.7 4500.9 5233.8 5250.2 5258.0 5268.9 5277.7 4402.0 4434.3 4495.2 4524.4 4531.9 2784.7 2814.6 2819.4 2822.4 2826.9 4916.3 4917.4 4966.2 4970.7 4976.3 5109.5 5323.2 5358.9 5424.7 5462.5 4964.4 4978.4 4983.9 4985.9 4987.7 5334.3 5335.2 5343.8 5370.7 5373.8 5069.5 5192.2 5251.9 5252.7 5263.2 4995.4 5002.9 5016.3 5022.0 5022.5 3743.1 3761.1 3767.4 3768.2 3801.9 5168.2 5289.9 5322.7 5331.4 5431.7 5147.2 5159.6 5169.4 5170.9 5175.7 5155.4 5174.4 5204.5 5216.8 5227.7 4896.4 4986.6 5044.9 5055.5 5061.2 4930.8 5066.9 5115.2 5200.1 5218.5 5047.5 5072.6 5081.8 5082.8 5105.6 17:14:19 INFO - PROCESS | 29940 | 17:14:19 INFO - PROCESS | 29940 | [#5] yui.html Cycles:180 Average:16926.87 Median:539.70 stddev:23931.00 (4434.1%) stddev-sans-first:23966.66 17:14:19 INFO - PROCESS | 29940 | Values: 581.5 597.6 620.0 642.2 654.7 12619.2 13492.6 13576.4 13701.4 13747.9 13545.1 14252.7 14362.9 14903.6 14906.9 739.7 762.3 765.9 766.2 766.4 595.7 605.9 608.2 616.0 762.1 656.1 764.9 795.4 797.4 802.4 783.9 790.5 791.7 792.5 796.5 580.0 580.2 580.4 580.6 580.8 10576.2 11036.0 13305.1 13993.6 14012.2 11749.5 12492.3 12557.1 12806.2 12974.3 10733.5 10764.0 10904.5 10913.9 11037.9 3153.4 3157.4 3170.1 3230.8 3261.2 68031.7 69384.0 70116.3 70315.3 71357.0 9606.0 9661.3 9883.6 9976.6 10001.7 17834.7 17856.6 17864.1 17879.8 17906.4 6521.7 6525.2 6610.8 6667.5 6671.7 3599.5 3616.5 3682.6 3691.7 3702.1 2758.8 2848.9 2858.8 2864.6 2875.8 480.8 482.9 490.3 494.0 498.9 431.9 458.9 467.1 472.2 484.1 6970.4 7134.0 7259.7 7385.7 7648.0 529.5 538.4 541.0 543.1 559.8 3747.1 3787.8 3806.4 3899.0 3914.2 78198.6 79711.2 81439.6 83343.0 106409.5 64938.0 74386.6 76178.6 77590.6 77823.0 53444.2 70035.6 72543.0 76739.6 77244.0 7548.5 8256.1 8317.6 8441.9 8533.4 42547.0 42572.6 43154.4 43227.8 44641.0 58196.4 62503.0 63258.7 63473.0 65030.3 9700.1 10036.1 10385.9 10427.1 10549.7 10997.5 11359.3 11458.9 12252.9 12354.9 10282.5 10316.7 10402.3 11051.8 11521.0 10516.8 10666.1 10687.5 11026.2 11274.2 9471.5 9683.2 9946.3 10111.9 10343.8 8511.9 8554.9 8619.2 8678.9 8946.0 8780.7 8795.5 8810.9 9149.4 9546.0 17:14:19 INFO - PROCESS | 29940 | -------- Summary: end -------- 17:14:19 INFO - PROCESS | 29940 | 17:14:20 INFO - TEST-INFO | 29940: exit 0 17:14:20 INFO - TEST-OK | dromaeo_css | took 1121127ms 17:14:20 INFO - TEST-START | kraken 17:14:20 INFO - operating with platform_type : linux_ 17:14:20 INFO - Initialising browser for kraken test... 17:14:20 INFO - TEST-INFO | started process 30244 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsjVIfw/profile http://localhost:52819/getInfo.html) 17:14:25 INFO - PROCESS | 30244 | __metrics Screen width/height:1600/1200 17:14:25 INFO - PROCESS | 30244 | colorDepth:24 17:14:25 INFO - PROCESS | 30244 | Browser inner width/height: 1024/697 17:14:25 INFO - PROCESS | 30244 | __metrics 17:14:25 INFO - PROCESS | 30244 | 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" 17:14:25 INFO - PROCESS | 30244 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:14:26 INFO - PROCESS | 30244 | console.error: PushService: 17:14:26 INFO - PROCESS | 30244 | stateChangeProcessEnqueue: Error transitioning state 17:14:26 INFO - PROCESS | 30244 | UnknownError 17:14:26 INFO - TEST-INFO | 30244: exit 0 17:14:26 INFO - Browser initialized. 17:14:26 INFO - Running cycle 1/1 for kraken test... 17:14:26 INFO - TEST-INFO | started process 30326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsjVIfw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:39 INFO - PROCESS | 30326 | RSS: Main: 174493696 17:14:39 INFO - PROCESS | 30326 | 17:14:41 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52819/tests/kraken/driver.html?testName=audio-beat-detection) 17:14:42 INFO - PROCESS | 30326 | RSS: Main: 233193472 17:14:42 INFO - PROCESS | 30326 | 17:14:45 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52819/tests/kraken/driver.html?testName=audio-dft) 17:14:46 INFO - PROCESS | 30326 | RSS: Main: 217993216 17:14:46 INFO - PROCESS | 30326 | 17:14:49 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52819/tests/kraken/driver.html?testName=audio-fft) 17:14:49 INFO - PROCESS | 30326 | RSS: Main: 299540480 17:14:49 INFO - PROCESS | 30326 | 17:14:52 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52819/tests/kraken/driver.html?testName=audio-oscillator) 17:14:52 INFO - PROCESS | 30326 | RSS: Main: 208977920 17:14:52 INFO - PROCESS | 30326 | 17:14:54 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52819/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:14:55 INFO - PROCESS | 30326 | RSS: Main: 213000192 17:14:55 INFO - PROCESS | 30326 | 17:14:58 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52819/tests/kraken/driver.html?testName=imaging-darkroom) 17:14:59 INFO - PROCESS | 30326 | RSS: Main: 249778176 17:14:59 INFO - PROCESS | 30326 | 17:15:02 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52819/tests/kraken/driver.html?testName=imaging-desaturate) 17:15:03 INFO - PROCESS | 30326 | RSS: Main: 243654656 17:15:03 INFO - PROCESS | 30326 | 17:15:06 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52819/tests/kraken/driver.html?testName=json-parse-financial) 17:15:06 INFO - PROCESS | 30326 | RSS: Main: 246501376 17:15:06 INFO - PROCESS | 30326 | 17:15:08 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52819/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:15:09 INFO - PROCESS | 30326 | RSS: Main: 209874944 17:15:09 INFO - PROCESS | 30326 | 17:15:11 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:15:11 INFO - PROCESS | 30326 | RSS: Main: 218222592 17:15:11 INFO - PROCESS | 30326 | 17:15:13 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:15:14 INFO - PROCESS | 30326 | RSS: Main: 222175232 17:15:14 INFO - PROCESS | 30326 | 17:15:17 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:15:18 INFO - PROCESS | 30326 | RSS: Main: 223268864 17:15:18 INFO - PROCESS | 30326 | 17:15:20 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:15:21 INFO - PROCESS | 30326 | RSS: Main: 220860416 17:15:21 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | Cycle 1(1): loaded http://localhost:52819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52819/tests/kraken/driver.html?testName=ai-astar) 17:15:23 INFO - PROCESS | 30326 | RSS: Main: 272293888 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | __start_tp_report 17:15:23 INFO - PROCESS | 30326 | _x_x_mozilla_page_load 17:15:23 INFO - PROCESS | 30326 | _x_x_mozilla_page_load_details 17:15:23 INFO - PROCESS | 30326 | |i|pagename|runs| 17:15:23 INFO - PROCESS | 30326 | |0;ai-astar;112;114;114;114;114;117;115;113;114;115 17:15:23 INFO - PROCESS | 30326 | |1;audio-beat-detection;151;127;125;158;128;125;125;131;163;125 17:15:23 INFO - PROCESS | 30326 | |2;audio-dft;161;161;163;163;161;162;161;161;161;160 17:15:23 INFO - PROCESS | 30326 | |3;audio-fft;75;75;77;111;76;76;77;77;102;76 17:15:23 INFO - PROCESS | 30326 | |4;audio-oscillator;83;83;113;84;85;84;120;84;83;110 17:15:23 INFO - PROCESS | 30326 | |5;imaging-gaussian-blur;117;116;116;116;116;116;117;116;117;117 17:15:23 INFO - PROCESS | 30326 | |6;imaging-darkroom;147;148;148;147;146;146;146;146;146;147 17:15:23 INFO - PROCESS | 30326 | |7;imaging-desaturate;95;95;94;94;94;95;94;95;95;96 17:15:23 INFO - PROCESS | 30326 | |8;json-parse-financial;65;66;64;64;65;66;65;65;65;65 17:15:23 INFO - PROCESS | 30326 | |9;json-stringify-tinderbox;66;66;66;67;66;65;66;66;66;70 17:15:23 INFO - PROCESS | 30326 | |10;stanford-crypto-aes;88;82;82;81;82;83;82;82;82;88 17:15:23 INFO - PROCESS | 30326 | |11;stanford-crypto-ccm;144;134;132;132;141;135;135;137;177;141 17:15:23 INFO - PROCESS | 30326 | |12;stanford-crypto-pbkdf2;174;176;175;172;173;171;175;175;171;171 17:15:23 INFO - PROCESS | 30326 | |13;stanford-crypto-sha256-iterative;71;69;68;70;73;71;69;69;70;71 17:15:23 INFO - PROCESS | 30326 | __end_tp_report 17:15:23 INFO - PROCESS | 30326 | __start_cc_report 17:15:23 INFO - PROCESS | 30326 | _x_x_mozilla_cycle_collect,1917 17:15:23 INFO - PROCESS | 30326 | __end_cc_report 17:15:23 INFO - PROCESS | 30326 | __startTimestamp1474330523633__endTimestamp 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | ------- Summary: start ------- 17:15:23 INFO - PROCESS | 30326 | Number of tests: 14 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.32 (1.2%) stddev-sans-first:1.13 17:15:23 INFO - PROCESS | 30326 | Values: 112.0 114.0 114.0 114.0 114.0 117.0 115.0 113.0 114.0 115.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:129.50 stddev:15.24 (11.8%) stddev-sans-first:15.14 17:15:23 INFO - PROCESS | 30326 | Values: 151.0 127.0 125.0 158.0 128.0 125.0 125.0 131.0 163.0 125.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 17:15:23 INFO - PROCESS | 30326 | Values: 161.0 161.0 163.0 163.0 161.0 162.0 161.0 161.0 161.0 160.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:13.00 (17.1%) stddev-sans-first:13.53 17:15:23 INFO - PROCESS | 30326 | Values: 75.0 75.0 77.0 111.0 76.0 76.0 77.0 77.0 102.0 76.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:83.50 stddev:15.00 (18.0%) stddev-sans-first:15.48 17:15:23 INFO - PROCESS | 30326 | Values: 83.0 83.0 113.0 84.0 85.0 84.0 120.0 84.0 83.0 110.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 17:15:23 INFO - PROCESS | 30326 | Values: 117.0 116.0 116.0 116.0 116.0 116.0 117.0 116.0 117.0 117.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 17:15:23 INFO - PROCESS | 30326 | Values: 147.0 148.0 148.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 17:15:23 INFO - PROCESS | 30326 | Values: 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 96.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 17:15:23 INFO - PROCESS | 30326 | Values: 65.0 66.0 64.0 64.0 65.0 66.0 65.0 65.0 65.0 65.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:1.35 (2.0%) stddev-sans-first:1.42 17:15:23 INFO - PROCESS | 30326 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 66.0 66.0 66.0 70.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.00 stddev:2.57 (3.1%) stddev-sans-first:2.06 17:15:23 INFO - PROCESS | 30326 | Values: 88.0 82.0 82.0 81.0 82.0 83.0 82.0 82.0 82.0 88.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:139.00 stddev:13.35 (9.6%) stddev-sans-first:14.11 17:15:23 INFO - PROCESS | 30326 | Values: 144.0 134.0 132.0 132.0 141.0 135.0 135.0 137.0 177.0 141.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:174.50 stddev:1.95 (1.1%) stddev-sans-first:2.05 17:15:23 INFO - PROCESS | 30326 | Values: 174.0 176.0 175.0 172.0 173.0 171.0 175.0 175.0 171.0 171.0 17:15:23 INFO - PROCESS | 30326 | 17:15:23 INFO - PROCESS | 30326 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.50 stddev:1.45 (2.1%) stddev-sans-first:1.50 17:15:23 INFO - PROCESS | 30326 | Values: 71.0 69.0 68.0 70.0 73.0 71.0 69.0 69.0 70.0 71.0 17:15:23 INFO - PROCESS | 30326 | -------- Summary: end -------- 17:15:23 INFO - PROCESS | 30326 | 17:15:24 INFO - TEST-INFO | 30326: exit 0 17:15:24 INFO - TEST-OK | kraken | took 64407ms 17:15:24 INFO - SUITE-END | took 1185s 17:15:25 INFO - Completed test suite (00:19:45) 17:15:25 INFO - javascript benchmark 17:15:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2524.2931979045866, 2545.3763827862176, 2575.6265341525477, 2584.4185058361873, 2601.8699065046753, 4645.69802962808, 4700.130475861968, 4708.128996135717, 4713.929291060633, 4719.197736384815, 4739.383880095591, 4761.8809529761775, 4765.690230135047, 4777.044591081789, 4788.688735232207, 4277.165952639237, 4350.54319296474, 4361.454818147731, 4406.118776244748, 4443.577860103301, 4315.050688848464, 4357.281048626973, 4389.648828093761, 4407.471103467572, 4451.043025749464, 4366.716163449366, 4444.022315090675, 4451.62161216297, 4470.82116715331, 4472.932906006413, 4379.1022840317655, 4400.669949753781, 4443.533428989974, 4467.910641787179, 4483.977580112111, 4285.421468101828, 4287.742735435852, 4320.179165958458, 4343.84796532123, 4396.3625274335045, 4672.4393072831135, 4678.812847486097, 4747.35910652061, 4778.402699662543, 4817.710937343771, 5007.474215207387, 5049.141645920203, 5053.620978426633, 5155.536001759822, 5175.560077393426, 5107.7446127693465, 5116.027954688598, 5120.487951204887, 5129.717865517395, 5179.067767801795, 4563.612092972068, 4614.515475875052, 4746.999999999965, 4767.594754445911, 4824.903501930012, 5048.545630893237, 5087.949120508822, 5108.2848401223855, 5113.488651134857, 5172.818951336685, 4827.348307978378, 4858.1012512685265, 4870.366852309177, 4900.583450406755, 4931.654784165074, 4869.683470574401, 4917.0903382874285, 4965.826196083192, 4974.552290293891, 5039.294498770175, 4899.412070551485, 4915.8033678652455, 4938.777755001033, 4953.777080031336, 5010.323606313174, 4910.21436570147, 4918.483559226302, 4921.7785199666105, 4924.8768780780765, 4989.575886049726, 4181.289180839264, 4210.221109094828, 4224.809883555247, 4231.640310573574, 4289.270823959934, 3693.4459831025024, 3733.197362567061, 3747.063234191452, 3776.169242766587, 3788.6590206881515, 4386.8025938832825, 4387.166438376698, 4390.187815254124, 4432.601065904019, 4441.911161776747, 5131.358580177478, 5135.768890399941, 5145.4854514549, 5148.279240906276, 5200.6619569727845, 4276.358546218092, 4302.182585308781, 4319.092990472036, 4343.457067866517, 4372.868813935587, 4564.109998550317, 4566.0, 4594.540545945379, 4622.029373831533, 4624.237000894815, 4747.905041899212, 4749.857504274877, 4750.263709125091, 4774.259989701602, 4814.229723244404, 4913.926291105565, 4989.600831933364, 5037.546620803999, 5055.898882022412, 5073.34046573943, 4721.929171062506, 4730.385049943623, 4731.645126615449, 4778.068276686013, 4799.688020278811, 4970.055689418999, 4972.801087956586, 4980.800767969241, 4990.4011518618, 5033.823816166563, 4972.403311602631, 5036.798528058836, 5044.243363495505, 5050.393952725696, 5115.641905066758, 4912.508749125059, 4945.0, 4977.402711674622, 4988.4263309719845, 5052.59579233653, 4978.377702787152, 4984.551390374883, 4985.052839960396, 4986.675866068694, 5050.292958985819, 4723.197056500472, 4911.435184953776, 4916.410030796327, 4959.479254678351, 5009.173486374707, 4856.417229932431, 4938.728369939688, 4968.8757781055765, 4996.7001979881325, 5059.494050594911, 4866.367372241586, 4880.267959806058, 4897.632677549269, 4899.093667671492, 4950.851474455772], "value": 4640.7869429156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.7680485793538, 547.5592148320603, 560.9551235901129, 562.0781917655047, 564.286177984849, 27154.26103560483, 37686.43470347947, 39356.0, 39396.80301598495, 43432.13135737283, 40004.199916001664, 40442.797786011026, 40493.392599110965, 40522.18955620886, 40523.59476405235, 7613.61931903404, 7763.495372800779, 7766.805829854271, 7771.727989520368, 7774.689012439496, 7609.733659321925, 7698.769036928873, 7699.230076992312, 7701.576413297266, 7705.922940770577, 7648.158702542716, 7703.1526532081425, 7706.229377062305, 7706.845863082735, 7710.653020614058, 7679.500832445901, 7715.421343399212, 7716.884246736304, 7724.30481256684, 7726.381889448859, 7551.131619863731, 7617.124030736481, 7620.618969051525, 7622.428317876182, 7638.5798781067015, 37149.0, 37392.065198369986, 37405.62594374049, 37490.62509374926, 37583.624163758286, 2913.1988703106604, 2952.867120979561, 2966.2287805170595, 3000.8799648014055, 3041.406925649499, 27642.308942276402, 28199.85900070507, 28228.71771282302, 30465.0, 30469.0, 2961.5557666350005, 3015.909522714322, 3021.9848900755574, 3039.6352437707396, 3040.239940014996, 249240.0, 251551.0, 251705.0, 252053.0, 253235.0, 53483.46516534863, 53862.46137538574, 53913.73043134838, 53913.73043134838, 54017.189742153896, 32125.678743212735, 32980.1754956128, 33130.17174570655, 34775.4783678245, 57594.7120264396, 14909.092726918363, 15065.870059745563, 15067.623309417353, 15080.813282074037, 15082.868784841181, 11473.082153427706, 11549.076073914066, 11563.537458501565, 11582.6525204244, 11583.478743456564, 17626.91186544085, 17702.203400846993, 17740.822591774177, 17749.467515974542, 17828.771403574552, 6558.442532384705, 6585.275619681831, 6684.565503242274, 6828.688086278151, 6879.724811007603, 707.179014038852, 900.5362238447204, 905.6015353244551, 924.3760461688333, 956.9760755981017, 25579.23262302134, 27255.86372068127, 27283.45433091327, 27508.312292192855, 27947.30131746682, 726.934575888162, 819.6598411659095, 850.8766228896775, 867.314821291173, 966.0773960867355, 14340.856591433952, 14725.0, 14855.48005819791, 15088.773668394982, 15092.1699306537, 336163.0, 338150.0, 338827.0, 339126.0, 339378.0, 47463.5253647466, 48264.758676206395, 48301.275480867815, 48393.0, 48405.0, 1077.21363404715, 1100.6753007862699, 1110.1618278199908, 1164.2025212729307, 1193.7015746063485, 25552.616710749724, 28629.28426789347, 29182.27044323909, 29442.558361624164, 29744.553831692974, 39226.0, 39563.604363955994, 39691.40462893001, 39694.60305396909, 39706.40440393339, 285747.0, 286626.0, 286759.0, 287165.0, 287506.0, 23930.760692392854, 24873.378165545284, 25090.62364064577, 25098.37254068663, 26048.21855344343, 40045.0, 40203.39694904637, 40641.796791015855, 40772.79613601913, 41497.37753933633, 34698.0, 34796.0, 34840.47739283862, 35039.64960350364, 35081.298374032885, 34342.48486272658, 34577.30845383128, 34590.82704586461, 34999.30001400009, 35034.0, 33965.49051764176, 34290.314193716484, 34871.82564087163, 35032.0, 35087.64912350844, 29215.0, 31828.5225721619, 31944.36111277808, 31991.84004079965, 32166.67833321637, 24677.38306542279, 24734.38164045913, 24824.0, 24841.727439033144, 24844.751552484242], "value": 16834.787878430965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.6123111499563, 372.51200926785896, 374.5393166405322, 375.7332294071209, 392.3604524624861, 12073.154879158463, 13090.34548272585, 13810.792838107436, 16209.675806483863, 16888.07115608644, 16324.18379081044, 16647.91676041618, 16789.076600786982, 16903.0, 16913.323467061302, 4641.907161856752, 5096.133657278253, 5114.693118412889, 5125.666831655949, 5127.230915362688, 3319.2199833039217, 5051.570616497602, 5131.974340128294, 5134.948650513503, 5139.743012849362, 3527.576690797108, 3530.823458827061, 3570.5184896496944, 5958.195643588125, 6038.607490513103, 5626.971865140689, 6097.26832780067, 6102.572819902609, 6103.176071230371, 6107.4808641265545, 5215.791368345262, 5225.581953443715, 5233.528982391603, 5241.764120614582, 5242.18746094356, 11504.597339093176, 11553.0, 11563.479643416067, 18648.0, 19681.31115410954, 1683.3939781678587, 1965.2924947019062, 2174.3259589527297, 2190.627593309141, 2244.461329280961, 8509.489430634181, 8548.572571371407, 8706.782330441727, 8989.640414383417, 9059.682911098145, 1705.1303835044216, 1818.7905043146293, 1846.2984066054814, 1853.6848735714962, 1900.8574356923148, 47366.76316618429, 65694.0, 69112.30887691109, 80485.0, 82224.58887705523, 15190.063154528721, 16174.544291013864, 16249.675006499803, 16262.186890655657, 16276.51170464888, 28444.573331400046, 28512.85743571269, 28587.428251434856, 28626.0, 28733.56899646507, 8514.276286515591, 8662.523561204069, 8776.078511756301, 8977.865332020025, 9029.548522573845, 7615.069719215684, 7772.533647981139, 7874.370050395954, 8016.679332826621, 8101.837963240702, 8394.664213431395, 8499.830003399897, 8581.828363432696, 8651.6971905983, 8714.912850871537, 2798.5802129680305, 3108.2384815719843, 3184.2039490127468, 3241.9837900810817, 3371.3425881953276, 744.155383639573, 780.9765707028798, 792.4175730837829, 836.3560058754846, 851.438050886412, 16287.592810179603, 16292.592685182964, 16402.179891005402, 16488.917555412147, 17039.14804259782, 779.6413649721078, 792.841431713648, 809.0938149272852, 824.3940703582862, 856.3320609924269, 6114.633122012693, 9265.2124569411, 9453.716388508356, 9569.808603828023, 9663.758406039764, 374674.0, 385326.0, 385438.0, 388130.0, 388539.0, 76291.0, 77115.61442192753, 78730.60634696904, 79108.0, 79781.0, 874.3355050161796, 883.6421249394006, 888.138505649519, 892.5492626223715, 895.7088946092676, 13887.722245555235, 14909.390856599835, 15131.0, 15152.242387880342, 15494.767578486557, 38056.619433806416, 38475.230495389376, 38495.80752096222, 39411.0, 40034.39948400718, 47713.52286477091, 51000.0, 51144.0, 51181.0, 51825.74087129691, 4810.148092984296, 7521.699132034876, 7596.08846938371, 7654.5839019781515, 7772.37820974332, 9348.298877584344, 10998.615048473264, 11148.665540033811, 11391.772164556829, 11517.53929842797, 7284.198738138906, 8774.736757897273, 9597.664081757384, 9669.806603868024, 9684.32209745311, 9864.5560949756, 9974.301798874008, 9976.900230997597, 10012.44931528773, 10297.485125743533, 9914.30599858003, 10016.899831001889, 10096.596136154472, 10177.542010609688, 10663.946680266548, 7172.318629730167, 7394.9277354784335, 7436.851262974818, 7509.962450187714, 7887.211278872296, 9532.0, 9616.08647178517, 9824.361416507903, 10167.339122956984, 10704.678859634223], "value": 8537.202800457633, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.2875276103666, 1364.2769332253904, 1392.5752645443142, 1606.2129556517295, 1663.7837081179437, 6734.225564060134, 7031.94520821877, 7281.635918204089, 7861.056673199217, 7863.724769633057, 7422.0, 7735.497192682486, 7750.341220996208, 7978.321842643368, 8014.5591992440395, 2194.242986169773, 2211.0602993727603, 2211.7124773779387, 2222.9110835566557, 2225.899834507443, 1851.685213513706, 1918.1272521002911, 2102.7686954435, 2113.2814842953394, 2152.0100753653337, 2137.4976880433087, 2179.923702670407, 2188.059134572133, 2194.681771143183, 2196.286206982729, 2148.4306658735527, 2156.051337411534, 2189.6168170570245, 2193.0, 2193.144673577306, 2122.4057263966115, 2147.538279269949, 2151.5262045498885, 2162.6915715991913, 2164.599549083409, 7858.292753652198, 7920.683172673086, 7921.247481489249, 7921.762347129596, 7922.920770792276, 4354.455693038371, 4516.096780643851, 4516.570925762047, 4528.909421811578, 4662.254039353687, 2800.355918138819, 2860.2420358604986, 2910.9272268193254, 2933.603963464948, 2975.4346674131843, 927.5501381829807, 949.6296444386695, 955.2358113509165, 969.8351280282369, 980.3039841712392, 9605.18355939746, 9870.753231169207, 9871.950640246825, 10106.0, 10133.949330253376, 3942.6845852331744, 4011.8796436106963, 4054.39184122384, 4132.2561938851295, 4137.855175068858, 8136.918630813734, 8137.877931831027, 8161.63272652732, 8167.101618821925, 8172.42793004484, 4452.799624016862, 4454.532274111237, 4462.553744625511, 4463.910721785546, 4465.26323156682, 4089.8773036808348, 4184.811683474251, 4186.748795072305, 4191.350340697197, 4195.643370313496, 3369.578802649669, 3398.2693720850134, 3447.379471695069, 3470.236547959445, 3567.5718913729997, 951.4291425144858, 982.8771403574553, 993.7813680990171, 999.8301986675607, 1012.422918936214, 707.9362857342863, 713.3472872321768, 721.0590179815287, 721.9784005632076, 741.1921006103265, 4591.95408045922, 4672.229082201398, 4690.530946905282, 4788.281757736368, 4852.490488498657, 763.969441222356, 775.3603277296253, 789.5933594198992, 803.2890891561011, 826.3967303868089, 3113.937721245608, 3197.328561002169, 3199.9040028799172, 3220.388126256004, 3227.5158726191257, 9557.283203759607, 9809.264305177141, 9887.406755594688, 9894.406335619742, 9895.059969302905, 9717.0, 9718.514074296258, 9722.0, 9750.707478775648, 9758.316917815826, 6688.230853451818, 6937.583744975217, 6973.267806880306, 7130.607816570035, 7225.0, 4923.975380123076, 4946.629002824874, 4991.625628077982, 5002.749862506933, 5205.45342739007, 8477.533735644598, 8797.38418310712, 8936.821263574822, 8965.282777377925, 9051.547422628973, 9114.954425227832, 9115.726528204164, 9131.08689131108, 9144.451332920045, 9163.49600771964, 6718.093057437183, 6875.140607424072, 6876.931230687829, 6878.312168783082, 6878.312168783082, 7205.207427182901, 7450.96274518624, 7496.887546686695, 7503.474756766974, 7507.286807753255, 5400.6412546867095, 6372.299047104722, 6658.400743933069, 6704.161979752531, 6803.013563033432, 6535.44448721864, 6543.345665433419, 6686.398224159849, 6726.822806008831, 6776.119104516381, 5900.350961394329, 5968.6717230551, 5979.970100149471, 5989.7304621293015, 5996.610220335664, 4077.531083925268, 4123.8556650517085, 4135.31767258399, 4165.875023749292, 4254.042840360894, 4070.9592904070582, 4112.074783173762, 4117.835286588623, 4191.601797829201, 4195.1819395217635], "value": 3984.485843771976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.9309262323355, 300.6482415573779, 307.33922067554755, 307.5095223119124, 310.5543545012905, 4862.683925544841, 4885.560299573038, 4902.975485122578, 4908.9936563004585, 4926.753662316888, 4558.680892337537, 4605.953940460595, 4754.215554433515, 4774.331593576893, 4845.466998630148, 3798.9240215195678, 3804.790736509498, 3809.657130858222, 3816.7709937403706, 3821.331267028273, 3806.7525610835346, 3812.0, 3846.7499612525235, 3847.1728578355646, 3849.1531862990096, 3579.6062433132333, 3648.5256916600806, 3794.146317078663, 3804.3641234269253, 3837.3882969153024, 3807.4098514730263, 3807.447920051605, 3810.90472738181, 3810.9519882032387, 3827.8086095695103, 3379.4761811919193, 3381.2392211752403, 3382.1375549235036, 3385.306012267479, 3413.1467133216697, 4576.977115114437, 4784.952150478485, 4793.544613261734, 4797.928031079537, 4805.591524720403, 4336.653067754603, 4343.67422443318, 4391.824327026915, 4399.252127138362, 4410.448693913261, 5079.314292570492, 5092.974535127338, 5101.82143624975, 5121.155009423441, 5165.741712914377, 3706.703463722896, 3717.070732316921, 3724.5344331958504, 3742.981285093584, 3745.793981331026, 5044.47033061527, 5301.3373328333955, 5329.360476742816, 5349.732513374354, 5396.487333703292, 3661.5972243053243, 3673.9632603674154, 3674.2651469706166, 3679.1537946272506, 3686.594474607791, 3730.8694195703565, 3737.6449237322413, 3738.27103714773, 3755.8122093895195, 3765.0963768695865, 4231.894202644897, 4305.686765536479, 4349.999500114991, 4352.6735494837385, 4356.194104090753, 5088.62607096082, 5111.616628752859, 5118.59051275897, 5123.333966584395, 5127.97436012825, 1285.1967520299813, 1299.6945717756319, 1309.4892991733232, 1310.4299629660936, 1314.7304802515462, 828.130463013833, 832.1595188859175, 850.7447765670274, 857.0829212742306, 897.4884315939852, 4463.709858859229, 4478.865634030919, 4481.596656300883, 4490.685652004328, 4500.862090507048, 5233.816816411408, 5250.159974404077, 5257.973710131425, 5268.92096618551, 5277.736113194325, 4402.0, 4434.262948207172, 4495.190865644216, 4524.366588677588, 4531.909361812811, 2784.7215278472395, 2814.5637426198973, 2819.3656427303695, 2822.379076603144, 2826.915192544227, 4916.2625606158645, 4917.360743103373, 4966.15575352191, 4970.65205435616, 4976.278439626234, 5109.518239710516, 5323.1635085895305, 5358.892822143535, 5424.728763561806, 5462.453754624506, 4964.361760618987, 4978.377702787152, 4983.875403114878, 4985.925211121908, 4987.675801072846, 5334.279872217201, 5335.1730481775385, 5343.7709326854265, 5370.704611246419, 5373.758180881948, 5069.453816585977, 5192.169252919514, 5251.868703282295, 5252.65857719247, 5263.236830659456, 4995.375578052744, 5002.899942001212, 5016.347874776256, 5022.0, 5022.522860328263, 3743.1016556026902, 3761.097336639242, 3767.434884767307, 3768.1898391845884, 3801.923961520699, 5168.198929165986, 5289.8677533060445, 5322.669332666834, 5331.360236771612, 5431.6740995540395, 5147.2279158126585, 5159.587233021274, 5169.405518365285, 5170.8965820684125, 5175.741212939413, 5155.355580552431, 5174.404943431554, 5204.505571970657, 5216.843494695166, 5227.660202086852, 4896.4124305083615, 4986.601071914166, 5044.915343201229, 5055.545000949932, 5061.240813877947, 4930.7534623267975, 5066.873328166826, 5115.232715092766, 5200.141976573975, 5218.504242096995, 5047.4952504749235, 5072.5688316493515, 5081.822136225214, 5082.847514574569, 5105.617078719037], "value": 3861.9342104209495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.4852309733145, 597.5936363272975, 619.9535034872383, 642.2004604267687, 654.6661202786578, 12619.179753316028, 13492.595222143327, 13576.389062492186, 13701.3834377453, 13747.862521374784, 13545.119567228123, 14252.71494570108, 14362.928185359058, 14903.552893413187, 14906.925465372686, 739.6560599321315, 762.3481922955876, 765.8621448139332, 766.2490759056128, 766.4481573267238, 595.6902410746409, 605.9030555111183, 608.2002167150206, 616.029753138806, 762.0778857582332, 656.0520048529876, 764.9005629268187, 795.4431897671624, 797.3740613618311, 802.4342838298985, 783.9451238413313, 790.549386849496, 791.734768852438, 792.5165648954134, 796.4584082823677, 579.9942000579982, 580.1703563903616, 580.4108829538022, 580.6022874331097, 580.7938181945433, 10576.153907687367, 11036.0, 13305.135166214164, 13993.580192594238, 14012.22932738699, 11749.4712737927, 12492.25046497213, 12557.121001529897, 12806.231626102464, 12974.286414247212, 10733.463326833706, 10764.02934463969, 10904.50929708165, 10913.890861091368, 11037.944810275978, 3153.4008538377634, 3157.431662300785, 3170.1440611035123, 3230.78999865008, 3261.2010057535817, 68031.65984170097, 69384.0, 70116.298837012, 70315.2968470314, 71357.0, 9606.0, 9661.275404344562, 9883.555240014217, 9976.610291471728, 10001.749956251006, 17834.732479012826, 17856.642867142844, 17864.106794660216, 17879.821201788076, 17906.373276935246, 6521.739130434729, 6525.1517302750335, 6610.8347291318105, 6667.533272670964, 6671.699773510204, 3599.478075679064, 3616.511770910946, 3682.576503702055, 3691.686206672463, 3702.129999450127, 2758.7930905182193, 2848.900288489893, 2858.7855910806775, 2864.64191976003, 2875.7555607773575, 480.79566184371504, 482.9275608658659, 490.2939766735889, 494.03662857428156, 498.9276554899519, 431.8985038515946, 458.94442781602845, 467.07985269019974, 472.17840956735034, 484.1213198045536, 6970.407515361251, 7134.02246208711, 7259.673314700851, 7385.724828259119, 7647.961760191163, 529.4811085136588, 538.4265756968815, 540.9702466364388, 543.0957455836068, 559.8068666310116, 3747.1194269346684, 3787.753796003251, 3806.4480650306105, 3899.0, 3914.236723838881, 78198.60900695574, 79711.20288797037, 81439.59280203561, 83343.0, 106409.46795265975, 64938.025929611096, 74386.6280668604, 76178.61910690411, 77590.61204693941, 77823.0, 53444.19833702497, 70035.64982175057, 72543.0, 76739.61630191813, 77244.0, 7548.452567223816, 8256.091829898587, 8317.625706843086, 8441.915580844114, 8533.35999800005, 42547.0, 42572.57427425686, 43154.35268470913, 43227.78386108049, 44641.0, 58196.4180358191, 62503.0, 63258.68370658117, 63473.0, 65030.34969650243, 9700.078492543196, 10036.146927510952, 10385.896141038493, 10427.113695335982, 10549.683509494727, 10997.450127493754, 11359.318440893572, 11458.94270528642, 12252.877471225174, 12354.876451235732, 10282.537285822305, 10316.742081447723, 10402.323848949794, 11051.778964420828, 11521.0, 10516.842247366449, 10666.093382062612, 10687.47313386596, 11026.228164028442, 11274.154438417316, 9471.47906865129, 9683.176929961031, 9946.254030947563, 10111.938246484013, 10343.793124137324, 8511.872321915052, 8554.871676924975, 8619.1811777881, 8678.913210867811, 8945.955270223607, 8780.692675756318, 8795.47227166372, 8810.86783698258, 9149.417981009925, 9545.952270238604], "value": 6204.300662342767, "unit": "score"}], "name": "dromaeo_css", "value": 6319.3494009176875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 114.0, 117.0, 115.0, 113.0, 114.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 127.0, 125.0, 158.0, 128.0, 125.0, 125.0, 131.0, 163.0, 125.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 163.0, 161.0, 162.0, 161.0, 161.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 111.0, 76.0, 76.0, 77.0, 77.0, 102.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 113.0, 84.0, 85.0, 84.0, 120.0, 84.0, 83.0, 110.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 96.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 64.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0, 70.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 82.0, 82.0, 81.0, 82.0, 83.0, 82.0, 82.0, 82.0, 88.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 134.0, 132.0, 132.0, 141.0, 135.0, 135.0, 137.0, 177.0, 141.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 175.0, 172.0, 173.0, 171.0, 175.0, 175.0, 171.0, 171.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 68.0, 70.0, 73.0, 71.0, 69.0, 69.0, 70.0, 71.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1543.1, "alertThreshold": 2.0}]} 17:15:25 INFO - Return code: 0 17:15:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:15:25 INFO - # TBPL SUCCESS # 17:15:25 INFO - Running post-action listener: _resource_record_post_action 17:15:25 INFO - [mozharness: 2016-09-20 00:15:25.450571Z] Finished run-tests step (success) 17:15:25 INFO - Running post-run listener: _resource_record_post_run 17:15:25 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 385236992; Read time: 12; Write time: 289068 17:15:25 INFO - TinderboxPrint: CPU usage
13.0% 17:15:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:15:25 INFO - TinderboxPrint: I/O write bytes / time
385,236,992 / 289,068 17:15:25 INFO - TinderboxPrint: CPU idle
8,338.5 (86.9%) 17:15:25 INFO - TinderboxPrint: CPU user
1,201.3 (12.5%) 17:15:25 INFO - TinderboxPrint: Swap in / out
0 / 0 17:15:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99151872; Read time: 0; Write time: 96516 17:15:25 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 285470720; Read time: 0; Write time: 192552 17:15:26 INFO - Running post-run listener: _upload_blobber_files 17:15:26 INFO - Blob upload gear active. 17:15:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:15:26 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 17:15:26 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'] 17:15:26 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 17:15:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:15:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:27 INFO - (blobuploader) - INFO - Done attempting. 17:15:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:15:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:15:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:28 INFO - (blobuploader) - INFO - Done attempting. 17:15:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:15:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:15:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:31 INFO - (blobuploader) - INFO - Done attempting. 17:15:31 INFO - (blobuploader) - INFO - Iteration through files over. 17:15:31 INFO - Return code: 0 17:15:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:15:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:15:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/257a735776ac4820043e2c86a833473546ced7286490b7fd91fc373be7f981b525b01d7dc790f87eeea7ef310593628ed7c918740fde6615dcf3e920d181951e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69956cf42eb1b237b1d903ecd5742c88f75f885fe1ff91f3ec3d326b0c2e96154546f5595165da1f0c667e2e48574f021d18f02ca145ae36ba7cfebeb85a3f85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b000ca5bac9fa4a4af057c1cf200042e2f9b7a645873e37e4fc95abd044a0a4f727017ccc704dcbc7e120481a9a80c6acb9410e0bd3f3b229165503361ae3ce1"} 17:15:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:15:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:15:31 INFO - Contents: 17:15:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/257a735776ac4820043e2c86a833473546ced7286490b7fd91fc373be7f981b525b01d7dc790f87eeea7ef310593628ed7c918740fde6615dcf3e920d181951e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69956cf42eb1b237b1d903ecd5742c88f75f885fe1ff91f3ec3d326b0c2e96154546f5595165da1f0c667e2e48574f021d18f02ca145ae36ba7cfebeb85a3f85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b000ca5bac9fa4a4af057c1cf200042e2f9b7a645873e37e4fc95abd044a0a4f727017ccc704dcbc7e120481a9a80c6acb9410e0bd3f3b229165503361ae3ce1"} 17:15:31 INFO - Running post-run listener: copy_logs_to_upload_dir 17:15:31 INFO - Copying logs to upload dir... 17:15:31 INFO - mkdir: /builds/slave/test/build/upload/logs 17:15:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.253201 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-19 17:15:31.740860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 17:15:31.744145) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 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/257a735776ac4820043e2c86a833473546ced7286490b7fd91fc373be7f981b525b01d7dc790f87eeea7ef310593628ed7c918740fde6615dcf3e920d181951e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69956cf42eb1b237b1d903ecd5742c88f75f885fe1ff91f3ec3d326b0c2e96154546f5595165da1f0c667e2e48574f021d18f02ca145ae36ba7cfebeb85a3f85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b000ca5bac9fa4a4af057c1cf200042e2f9b7a645873e37e4fc95abd044a0a4f727017ccc704dcbc7e120481a9a80c6acb9410e0bd3f3b229165503361ae3ce1"} build_url:https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/Pi0J7-RFQd22hjayBFiXPg/artifacts/public/build/firefox-52.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/257a735776ac4820043e2c86a833473546ced7286490b7fd91fc373be7f981b525b01d7dc790f87eeea7ef310593628ed7c918740fde6615dcf3e920d181951e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69956cf42eb1b237b1d903ecd5742c88f75f885fe1ff91f3ec3d326b0c2e96154546f5595165da1f0c667e2e48574f021d18f02ca145ae36ba7cfebeb85a3f85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b000ca5bac9fa4a4af057c1cf200042e2f9b7a645873e37e4fc95abd044a0a4f727017ccc704dcbc7e120481a9a80c6acb9410e0bd3f3b229165503361ae3ce1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 17:15:31.769878) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 17:15:31.770242) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474329284.32018-736799650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 17:15:31.854924) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 17:15:31.855582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 17:15:31.856173) ========= ========= Total master_lag: 0.19 =========