builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1474200365.37 results: success (0) buildid: 20160918000553 builduid: e66f368b4db4489a8f57c64eacec42df revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.374168) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.374601) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.374935) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.389471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.389758) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 05:06:05-- 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% 38.4M=0s 2016-09-18 05:06:05 (38.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162965 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.566645) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.566971) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021247 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:05.616794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 05:06:05.617150) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fd81de7deaaba19cb7a899b08da0472e32bd215b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fd81de7deaaba19cb7a899b08da0472e32bd215b --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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 05:06:05,758 truncating revision to first 12 chars 2016-09-18 05:06:05,759 Setting DEBUG logging. 2016-09-18 05:06:05,759 attempt 1/10 2016-09-18 05:06:05,759 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fd81de7deaab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-18 05:06:06,481 unpacking tar archive at: fx-team-fd81de7deaab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 05:06:06.681276) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:06.682146) ========= script_repo_revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:06.683253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:06.684005) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:06.696622) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-18 05:06:06.697374) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:06:06 INFO - MultiFileLogger online at 20160918 05:06:06 in /builds/slave/test 05:06:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:06:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:06 INFO - {'append_to_log': False, 05:06:06 INFO - 'base_work_dir': '/builds/slave/test', 05:06:06 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:06:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:06 INFO - 'branch': 'Fx-Team-Non-PGO', 05:06:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:06 INFO - 'config_files': ('talos/linux_config.py',), 05:06:06 INFO - 'default_actions': ('clobber', 05:06:06 INFO - 'read-buildbot-config', 05:06:06 INFO - 'download-and-extract', 05:06:06 INFO - 'populate-webroot', 05:06:06 INFO - 'create-virtualenv', 05:06:06 INFO - 'install', 05:06:06 INFO - 'run-tests'), 05:06:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:06 INFO - 'download_minidump_stackwalk': True, 05:06:06 INFO - 'download_symbols': 'ondemand', 05:06:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:06 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:06 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:06 INFO - 'installer_path': 'installer.exe', 05:06:06 INFO - 'log_level': 'info', 05:06:06 INFO - 'log_name': 'talos', 05:06:06 INFO - 'log_to_console': True, 05:06:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:06:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:06:06 INFO - 'opt_config_files': (), 05:06:06 INFO - 'pip_index': False, 05:06:06 INFO - 'sps_profile': False, 05:06:06 INFO - 'sps_profile_interval': 0, 05:06:06 INFO - 'suite': 'dromaeojs', 05:06:06 INFO - 'system_bits': '32', 05:06:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:06 INFO - 'title': 'talos-linux64-ix-006', 05:06:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:06 INFO - 'use_talos_json': True, 05:06:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:06 INFO - 'work_dir': 'build'} 05:06:06 INFO - [mozharness: 2016-09-18 12:06:06.938330Z] Running clobber step. 05:06:06 INFO - Running pre-action listener: _resource_record_pre_action 05:06:06 INFO - Running main action method: clobber 05:06:06 INFO - rmtree: /builds/slave/test/build 05:06:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:07 INFO - Running post-action listener: _resource_record_post_action 05:06:07 INFO - [mozharness: 2016-09-18 12:06:07.391038Z] Finished clobber step (success) 05:06:07 INFO - [mozharness: 2016-09-18 12:06:07.391139Z] Running read-buildbot-config step. 05:06:07 INFO - Running pre-action listener: _resource_record_pre_action 05:06:07 INFO - Running main action method: read_buildbot_config 05:06:07 INFO - Using buildbot properties: 05:06:07 INFO - { 05:06:07 INFO - "project": "", 05:06:07 INFO - "product": "firefox", 05:06:07 INFO - "script_repo_revision": "production", 05:06:07 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:06:07 INFO - "repository": "", 05:06:07 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:06:07 INFO - "buildid": "20160918000553", 05:06:07 INFO - "pgo_build": "False", 05:06:07 INFO - "basedir": "/builds/slave/test", 05:06:07 INFO - "buildnumber": 3330, 05:06:07 INFO - "slavename": "talos-linux64-ix-006", 05:06:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:06:07 INFO - "platform": "ubuntu64_hw", 05:06:07 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:06:07 INFO - "branch": "fx-team", 05:06:07 INFO - "repo_path": "integration/fx-team", 05:06:07 INFO - "revision": "fd81de7deaaba19cb7a899b08da0472e32bd215b", 05:06:07 INFO - "stage_platform": "linux64", 05:06:07 INFO - "builduid": "e66f368b4db4489a8f57c64eacec42df", 05:06:07 INFO - "slavebuilddir": "test" 05:06:07 INFO - } 05:06:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:06:07 INFO - Running post-action listener: _resource_record_post_action 05:06:07 INFO - [mozharness: 2016-09-18 12:06:07.393602Z] Finished read-buildbot-config step (success) 05:06:07 INFO - [mozharness: 2016-09-18 12:06:07.393692Z] Running download-and-extract step. 05:06:07 INFO - Running pre-action listener: _resource_record_pre_action 05:06:07 INFO - Running main action method: download_and_extract 05:06:07 INFO - mkdir: /builds/slave/test/build/tests 05:06:07 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')]} 05:06:07 INFO - trying https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:06:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:06:07 INFO - Downloaded 1361 bytes. 05:06:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:06:07 INFO - Using the following test package requirements: 05:06:07 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:06:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:06:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'jsshell-linux-x86_64.zip'], 05:06:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:06:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:06:07 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:06:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:06:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:06:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:06:08 INFO - Expected file size: 20365610 05:06:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:06:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:06:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:06:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:06:13 INFO - Expected file size: 20365610 05:06:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:06:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:06:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:06:18 INFO - Expected file size: 14414059 05:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:19 INFO - trying https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:06:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:06:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:06:20 INFO - Downloaded 55602959 bytes. 05:06:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:06:20 INFO - mkdir: /builds/slave/test/properties 05:06:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:20 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:20 INFO - Contents: 05:06:20 INFO - build_url:https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:06:20 INFO - Running post-action listener: _resource_record_post_action 05:06:20 INFO - Running post-action listener: set_extra_try_arguments 05:06:20 INFO - [mozharness: 2016-09-18 12:06:20.817025Z] Finished download-and-extract step (success) 05:06:20 INFO - [mozharness: 2016-09-18 12:06:20.817253Z] Running populate-webroot step. 05:06:20 INFO - Running pre-action listener: _resource_record_pre_action 05:06:20 INFO - Running main action method: populate_webroot 05:06:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:20 INFO - '--tppagecycles', 05:06:20 INFO - '7'], 05:06:20 INFO - 'tests': ['tsvgm']}}, 05:06:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['damp', 'tps']}, 05:06:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'tests': ['damp', 'tps']}, 05:06:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['dromaeo_dom']}, 05:06:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['basic_compositor_video']}, 05:06:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:06:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['a11yr', 05:06:20 INFO - 'ts_paint', 05:06:20 INFO - 'tpaint', 05:06:20 INFO - 'sessionrestore', 05:06:20 INFO - 'sessionrestore_no_auto_restore', 05:06:20 INFO - 'tabpaint']}, 05:06:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['tsvgx', 05:06:20 INFO - 'tsvgr_opacity', 05:06:20 INFO - 'tart', 05:06:20 INFO - 'tscrollx', 05:06:20 INFO - 'cart']}, 05:06:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:20 INFO - 'tsvgr_opacity', 05:06:20 INFO - 'tart', 05:06:20 INFO - 'tscrollx', 05:06:20 INFO - 'cart']}, 05:06:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'talos_options': ['--disable-e10s'], 05:06:20 INFO - 'tests': ['tp5o']}, 05:06:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'tests': ['tp5o']}, 05:06:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'talos_options': ['--disable-e10s', 05:06:20 INFO - '--xperf_path', 05:06:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:20 INFO - 'tests': ['tp5n']}, 05:06:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:20 INFO - 'talos_options': ['--xperf_path', 05:06:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:20 INFO - 'tests': ['tp5n']}}, 05:06:20 INFO - 'talos.zip': {'path': '', 05:06:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:20 INFO - Running post-action listener: _resource_record_post_action 05:06:20 INFO - [mozharness: 2016-09-18 12:06:20.832646Z] Finished populate-webroot step (success) 05:06:20 INFO - [mozharness: 2016-09-18 12:06:20.832735Z] Running create-virtualenv step. 05:06:20 INFO - Running pre-action listener: _resource_record_pre_action 05:06:20 INFO - Running main action method: create_virtualenv 05:06:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:20 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 05:06:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:20 INFO - Using real prefix '/usr' 05:06:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:23 INFO - Installing pip.................done. 05:06:23 INFO - Return code: 0 05:06:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:06:23 INFO - Using env: {'DISPLAY': ':0', 05:06:23 INFO - 'HOME': '/home/cltbld', 05:06:23 INFO - 'LANG': 'en_US.UTF-8', 05:06:23 INFO - 'LANGUAGE': 'en_US:en', 05:06:23 INFO - 'LOGNAME': 'cltbld', 05:06:23 INFO - 'MAIL': '/var/mail/cltbld', 05:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:23 INFO - 'MOZ_NO_REMOTE': '1', 05:06:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:23 INFO - 'NO_EM_RESTART': '1', 05:06:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:23 INFO - 'PWD': '/builds/slave/test', 05:06:23 INFO - 'SHELL': '/bin/bash', 05:06:23 INFO - 'SHLVL': '1', 05:06:23 INFO - 'TERM': 'linux', 05:06:23 INFO - 'TMOUT': '86400', 05:06:23 INFO - 'USER': 'cltbld', 05:06:23 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:23 INFO - '_': '/tools/buildbot/bin/python'} 05:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:23 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 05:06:23 INFO - Cleaning up... 05:06:23 INFO - Return code: 0 05:06:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:06:23 INFO - Using env: {'DISPLAY': ':0', 05:06:23 INFO - 'HOME': '/home/cltbld', 05:06:23 INFO - 'LANG': 'en_US.UTF-8', 05:06:23 INFO - 'LANGUAGE': 'en_US:en', 05:06:23 INFO - 'LOGNAME': 'cltbld', 05:06:23 INFO - 'MAIL': '/var/mail/cltbld', 05:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:23 INFO - 'MOZ_NO_REMOTE': '1', 05:06:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:23 INFO - 'NO_EM_RESTART': '1', 05:06:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:23 INFO - 'PWD': '/builds/slave/test', 05:06:23 INFO - 'SHELL': '/bin/bash', 05:06:23 INFO - 'SHLVL': '1', 05:06:23 INFO - 'TERM': 'linux', 05:06:23 INFO - 'TMOUT': '86400', 05:06:23 INFO - 'USER': 'cltbld', 05:06:23 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:23 INFO - '_': '/tools/buildbot/bin/python'} 05:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:23 INFO - Downloading/unpacking psutil>=3.1.1 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:25 INFO - Installing collected packages: psutil 05:06:25 INFO - Running setup.py install for psutil 05:06:25 INFO - building 'psutil._psutil_linux' extension 05:06:25 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 05:06:26 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 05:06:26 INFO - building 'psutil._psutil_posix' extension 05:06:26 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 05:06:26 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 05:06:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:26 INFO - Successfully installed psutil 05:06:26 INFO - Cleaning up... 05:06:26 INFO - Return code: 0 05:06:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:06:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:06:26 INFO - Using env: {'DISPLAY': ':0', 05:06:26 INFO - 'HOME': '/home/cltbld', 05:06:26 INFO - 'LANG': 'en_US.UTF-8', 05:06:26 INFO - 'LANGUAGE': 'en_US:en', 05:06:26 INFO - 'LOGNAME': 'cltbld', 05:06:26 INFO - 'MAIL': '/var/mail/cltbld', 05:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:26 INFO - 'MOZ_NO_REMOTE': '1', 05:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:26 INFO - 'NO_EM_RESTART': '1', 05:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:26 INFO - 'PWD': '/builds/slave/test', 05:06:26 INFO - 'SHELL': '/bin/bash', 05:06:26 INFO - 'SHLVL': '1', 05:06:26 INFO - 'TERM': 'linux', 05:06:26 INFO - 'TMOUT': '86400', 05:06:26 INFO - 'USER': 'cltbld', 05:06:26 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:26 INFO - '_': '/tools/buildbot/bin/python'} 05:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:06:28 INFO - Installing collected packages: mozsystemmonitor 05:06:28 INFO - Running setup.py install for mozsystemmonitor 05:06:28 INFO - Successfully installed mozsystemmonitor 05:06:28 INFO - Cleaning up... 05:06:28 INFO - Return code: 0 05:06:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:28 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 05:06:28 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 05:06:28 INFO - Using env: {'DISPLAY': ':0', 05:06:28 INFO - 'HOME': '/home/cltbld', 05:06:28 INFO - 'LANG': 'en_US.UTF-8', 05:06:28 INFO - 'LANGUAGE': 'en_US:en', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MAIL': '/var/mail/cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SHLVL': '1', 05:06:28 INFO - 'TERM': 'linux', 05:06:28 INFO - 'TMOUT': '86400', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - '_': '/tools/buildbot/bin/python'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 INFO - Downloading/unpacking jsonschema==2.5.1 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Installing collected packages: jsonschema, functools32 05:06:31 INFO - Running setup.py install for functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Successfully installed jsonschema functools32 05:06:31 INFO - Cleaning up... 05:06:31 INFO - Return code: 0 05:06:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:06:31 INFO - Using env: {'DISPLAY': ':0', 05:06:31 INFO - 'HOME': '/home/cltbld', 05:06:31 INFO - 'LANG': 'en_US.UTF-8', 05:06:31 INFO - 'LANGUAGE': 'en_US:en', 05:06:31 INFO - 'LOGNAME': 'cltbld', 05:06:31 INFO - 'MAIL': '/var/mail/cltbld', 05:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:31 INFO - 'MOZ_NO_REMOTE': '1', 05:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:31 INFO - 'NO_EM_RESTART': '1', 05:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:31 INFO - 'PWD': '/builds/slave/test', 05:06:31 INFO - 'SHELL': '/bin/bash', 05:06:31 INFO - 'SHLVL': '1', 05:06:31 INFO - 'TERM': 'linux', 05:06:31 INFO - 'TMOUT': '86400', 05:06:31 INFO - 'USER': 'cltbld', 05:06:31 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:31 INFO - '_': '/tools/buildbot/bin/python'} 05:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:31 INFO - Cleaning up... 05:06:31 INFO - Return code: 0 05:06:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:06:31 INFO - Using env: {'DISPLAY': ':0', 05:06:31 INFO - 'HOME': '/home/cltbld', 05:06:31 INFO - 'LANG': 'en_US.UTF-8', 05:06:31 INFO - 'LANGUAGE': 'en_US:en', 05:06:31 INFO - 'LOGNAME': 'cltbld', 05:06:31 INFO - 'MAIL': '/var/mail/cltbld', 05:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:31 INFO - 'MOZ_NO_REMOTE': '1', 05:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:31 INFO - 'NO_EM_RESTART': '1', 05:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:31 INFO - 'PWD': '/builds/slave/test', 05:06:31 INFO - 'SHELL': '/bin/bash', 05:06:31 INFO - 'SHLVL': '1', 05:06:31 INFO - 'TERM': 'linux', 05:06:31 INFO - 'TMOUT': '86400', 05:06:31 INFO - 'USER': 'cltbld', 05:06:31 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:31 INFO - '_': '/tools/buildbot/bin/python'} 05:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:31 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:33 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:33 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 05:06:33 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 05:06:33 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 05:06:33 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 05:06:33 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 05:06:33 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 05:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:34 INFO - Downloading docopt-0.6.1.tar.gz 05:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:34 INFO - Running setup.py install for blobuploader 05:06:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:34 INFO - Running setup.py install for requests 05:06:34 INFO - Running setup.py install for docopt 05:06:34 INFO - Successfully installed blobuploader requests docopt 05:06:34 INFO - Cleaning up... 05:06:34 INFO - Return code: 0 05:06:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:35 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')]} 05:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:35 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')]} 05:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:35 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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:35 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 05:06:35 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 05:06:35 INFO - Using env: {'DISPLAY': ':0', 05:06:35 INFO - 'HOME': '/home/cltbld', 05:06:35 INFO - 'LANG': 'en_US.UTF-8', 05:06:35 INFO - 'LANGUAGE': 'en_US:en', 05:06:35 INFO - 'LOGNAME': 'cltbld', 05:06:35 INFO - 'MAIL': '/var/mail/cltbld', 05:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:35 INFO - 'MOZ_NO_REMOTE': '1', 05:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:35 INFO - 'NO_EM_RESTART': '1', 05:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:35 INFO - 'PWD': '/builds/slave/test', 05:06:35 INFO - 'SHELL': '/bin/bash', 05:06:35 INFO - 'SHLVL': '1', 05:06:35 INFO - 'TERM': 'linux', 05:06:35 INFO - 'TMOUT': '86400', 05:06:35 INFO - 'USER': 'cltbld', 05:06:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:35 INFO - '_': '/tools/buildbot/bin/python'} 05:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:35 INFO - Running setup.py (path:/tmp/pip-VKTJXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:35 INFO - Running setup.py (path:/tmp/pip-2HY0nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:35 INFO - Running setup.py (path:/tmp/pip-y2dGup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:35 INFO - Running setup.py (path:/tmp/pip-Tt7SPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:35 INFO - Running setup.py (path:/tmp/pip-nmqYrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:35 INFO - Running setup.py (path:/tmp/pip-yrOkyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:35 INFO - Running setup.py (path:/tmp/pip-uIJbJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:35 INFO - Running setup.py (path:/tmp/pip-Jjpq5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:35 INFO - Running setup.py (path:/tmp/pip-RDa1pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:35 INFO - Running setup.py (path:/tmp/pip-v5v_Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:35 INFO - Running setup.py (path:/tmp/pip-8mHoE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:35 INFO - Running setup.py (path:/tmp/pip-nE1iaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:36 INFO - Running setup.py (path:/tmp/pip-FZov5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:36 INFO - Running setup.py (path:/tmp/pip-crhwfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:36 INFO - Running setup.py (path:/tmp/pip-CNCcnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:36 INFO - Running setup.py (path:/tmp/pip-2YArL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:36 INFO - Running setup.py (path:/tmp/pip-HzKiuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:36 INFO - Running setup.py install for manifestparser 05:06:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozcrash 05:06:36 INFO - Running setup.py install for mozdebug 05:06:36 INFO - Running setup.py install for mozdevice 05:06:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozfile 05:06:36 INFO - Running setup.py install for mozhttpd 05:06:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozinfo 05:06:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozInstall 05:06:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozleak 05:06:37 INFO - Running setup.py install for mozlog 05:06:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for moznetwork 05:06:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozprocess 05:06:37 INFO - Running setup.py install for mozprofile 05:06:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozrunner 05:06:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozscreenshot 05:06:37 INFO - Running setup.py install for moztest 05:06:38 INFO - Running setup.py install for mozversion 05:06:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:38 INFO - Cleaning up... 05:06:38 INFO - Return code: 0 05:06:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:38 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')]} 05:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:38 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')]} 05:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:38 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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:38 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 05:06:38 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 05:06:38 INFO - Using env: {'DISPLAY': ':0', 05:06:38 INFO - 'HOME': '/home/cltbld', 05:06:38 INFO - 'LANG': 'en_US.UTF-8', 05:06:38 INFO - 'LANGUAGE': 'en_US:en', 05:06:38 INFO - 'LOGNAME': 'cltbld', 05:06:38 INFO - 'MAIL': '/var/mail/cltbld', 05:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:38 INFO - 'MOZ_NO_REMOTE': '1', 05:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:38 INFO - 'NO_EM_RESTART': '1', 05:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:38 INFO - 'PWD': '/builds/slave/test', 05:06:38 INFO - 'SHELL': '/bin/bash', 05:06:38 INFO - 'SHLVL': '1', 05:06:38 INFO - 'TERM': 'linux', 05:06:38 INFO - 'TMOUT': '86400', 05:06:38 INFO - 'USER': 'cltbld', 05:06:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:38 INFO - '_': '/tools/buildbot/bin/python'} 05:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:38 INFO - Running setup.py (path:/tmp/pip-iomwEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:38 INFO - Running setup.py (path:/tmp/pip-40eQvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:38 INFO - Running setup.py (path:/tmp/pip-JgS1_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:38 INFO - Running setup.py (path:/tmp/pip-69Xk0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:38 INFO - Running setup.py (path:/tmp/pip-KLWU_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:38 INFO - Running setup.py (path:/tmp/pip-Bib_tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:38 INFO - Running setup.py (path:/tmp/pip-pxpDkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:38 INFO - Running setup.py (path:/tmp/pip-zhp7by-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:39 INFO - Running setup.py (path:/tmp/pip-jst8lS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:39 INFO - Running setup.py (path:/tmp/pip-8FxLeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:39 INFO - Running setup.py (path:/tmp/pip-3eAkUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:39 INFO - Running setup.py (path:/tmp/pip-K_sgZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:39 INFO - Running setup.py (path:/tmp/pip-b4tC9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:39 INFO - Running setup.py (path:/tmp/pip-hPiEOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:39 INFO - Running setup.py (path:/tmp/pip-KHhoFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:39 INFO - Running setup.py (path:/tmp/pip-iCHrhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:39 INFO - Running setup.py (path:/tmp/pip-Z5M1U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:39 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)) 05:06:39 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)) 05:06:39 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)) 05:06:39 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)) 05:06:39 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)) 05:06:39 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)) 05:06:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:41 INFO - Downloading blessings-1.6.tar.gz 05:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:41 INFO - Installing collected packages: blessings 05:06:41 INFO - Running setup.py install for blessings 05:06:41 INFO - Successfully installed blessings 05:06:41 INFO - Cleaning up... 05:06:41 INFO - Return code: 0 05:06:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:42 INFO - Reading from file tmpfile_stdout 05:06:42 INFO - Current package versions: 05:06:42 INFO - argparse == 1.2.1 05:06:42 INFO - blessings == 1.6 05:06:42 INFO - blobuploader == 1.2.4 05:06:42 INFO - docopt == 0.6.1 05:06:42 INFO - functools32 == 3.2.3-2 05:06:42 INFO - jsonschema == 2.5.1 05:06:42 INFO - manifestparser == 1.1 05:06:42 INFO - mozInstall == 1.12 05:06:42 INFO - mozcrash == 0.17 05:06:42 INFO - mozdebug == 0.1 05:06:42 INFO - mozdevice == 0.48 05:06:42 INFO - mozfile == 1.2 05:06:42 INFO - mozhttpd == 0.7 05:06:42 INFO - mozinfo == 0.9 05:06:42 INFO - mozleak == 0.1 05:06:42 INFO - mozlog == 3.4 05:06:42 INFO - moznetwork == 0.27 05:06:42 INFO - mozprocess == 0.23 05:06:42 INFO - mozprofile == 0.28 05:06:42 INFO - mozrunner == 6.12 05:06:42 INFO - mozscreenshot == 0.1 05:06:42 INFO - mozsystemmonitor == 0.3 05:06:42 INFO - moztest == 0.7 05:06:42 INFO - mozversion == 1.4 05:06:42 INFO - psutil == 3.1.1 05:06:42 INFO - requests == 1.2.3 05:06:42 INFO - wsgiref == 0.1.2 05:06:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:42 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 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:42 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 05:06:42 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 05:06:42 INFO - Using env: {'DISPLAY': ':0', 05:06:42 INFO - 'HOME': '/home/cltbld', 05:06:42 INFO - 'LANG': 'en_US.UTF-8', 05:06:42 INFO - 'LANGUAGE': 'en_US:en', 05:06:42 INFO - 'LOGNAME': 'cltbld', 05:06:42 INFO - 'MAIL': '/var/mail/cltbld', 05:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:42 INFO - 'MOZ_NO_REMOTE': '1', 05:06:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:42 INFO - 'NO_EM_RESTART': '1', 05:06:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:42 INFO - 'PWD': '/builds/slave/test', 05:06:42 INFO - 'SHELL': '/bin/bash', 05:06:42 INFO - 'SHLVL': '1', 05:06:42 INFO - 'TERM': 'linux', 05:06:42 INFO - 'TMOUT': '86400', 05:06:42 INFO - 'USER': 'cltbld', 05:06:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:42 INFO - '_': '/tools/buildbot/bin/python'} 05:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 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)) 05:06:42 INFO - Cleaning up... 05:06:42 INFO - Return code: 0 05:06:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b0adccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', '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 05:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:06:42 INFO - Using env: {'DISPLAY': ':0', 05:06:42 INFO - 'HOME': '/home/cltbld', 05:06:42 INFO - 'LANG': 'en_US.UTF-8', 05:06:42 INFO - 'LANGUAGE': 'en_US:en', 05:06:42 INFO - 'LOGNAME': 'cltbld', 05:06:42 INFO - 'MAIL': '/var/mail/cltbld', 05:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:42 INFO - 'MOZ_NO_REMOTE': '1', 05:06:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:42 INFO - 'NO_EM_RESTART': '1', 05:06:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:42 INFO - 'PWD': '/builds/slave/test', 05:06:42 INFO - 'SHELL': '/bin/bash', 05:06:42 INFO - 'SHLVL': '1', 05:06:42 INFO - 'TERM': 'linux', 05:06:42 INFO - 'TMOUT': '86400', 05:06:42 INFO - 'USER': 'cltbld', 05:06:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:42 INFO - '_': '/tools/buildbot/bin/python'} 05:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:42 INFO - Cleaning up... 05:06:42 INFO - Return code: 0 05:06:42 INFO - Running post-action listener: _resource_record_post_action 05:06:42 INFO - Running post-action listener: _start_resource_monitoring 05:06:42 INFO - Starting resource monitoring. 05:06:42 INFO - [mozharness: 2016-09-18 12:06:42.570969Z] Finished create-virtualenv step (success) 05:06:42 INFO - [mozharness: 2016-09-18 12:06:42.571420Z] Running install step. 05:06:42 INFO - Running pre-action listener: _resource_record_pre_action 05:06:42 INFO - Running main action method: install 05:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:42 INFO - Reading from file tmpfile_stdout 05:06:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:42 INFO - Reading from file tmpfile_stdout 05:06:42 INFO - Output received: 05:06:42 INFO - Usage: mozinstall [options] installer 05:06:42 INFO - Options: 05:06:42 INFO - -h, --help show this help message and exit 05:06:42 INFO - -d DEST, --destination=DEST 05:06:42 INFO - Directory to install application into. [default: 05:06:42 INFO - "/builds/slave/test"] 05:06:42 INFO - --app=APP Application being installed. [default: firefox] 05:06:42 INFO - mkdir: /builds/slave/test/build/application 05:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:06:58 INFO - Reading from file tmpfile_stdout 05:06:58 INFO - Output received: 05:06:58 INFO - /builds/slave/test/build/application/firefox/firefox 05:06:58 INFO - Running post-action listener: _resource_record_post_action 05:06:58 INFO - [mozharness: 2016-09-18 12:06:58.924796Z] Finished install step (success) 05:06:58 INFO - [mozharness: 2016-09-18 12:06:58.925041Z] Running run-tests step. 05:06:58 INFO - Running pre-action listener: _resource_record_pre_action 05:06:58 INFO - Running main action method: run_tests 05:06:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:58 INFO - Python 2.7.3 05:06:58 INFO - Return code: 0 05:06:58 INFO - grabbing minidump binary from tooltool 05:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:58 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 0x1d37f60>, '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 0x1d38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d391d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:58 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 05:06:58 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 05:06:58 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 05:06:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:59 INFO - Return code: 0 05:06:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:06:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:06:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 05:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 05:06:59 INFO - Using env: {'DISPLAY': ':0', 05:06:59 INFO - 'HOME': '/home/cltbld', 05:06:59 INFO - 'LANG': 'en_US.UTF-8', 05:06:59 INFO - 'LANGUAGE': 'en_US:en', 05:06:59 INFO - 'LOGNAME': 'cltbld', 05:06:59 INFO - 'MAIL': '/var/mail/cltbld', 05:06:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:59 INFO - 'MOZ_NO_REMOTE': '1', 05:06:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:59 INFO - 'NO_EM_RESTART': '1', 05:06:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:59 INFO - 'PWD': '/builds/slave/test', 05:06:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:59 INFO - 'SHELL': '/bin/bash', 05:06:59 INFO - 'SHLVL': '1', 05:06:59 INFO - 'TERM': 'linux', 05:06:59 INFO - 'TMOUT': '86400', 05:06:59 INFO - 'USER': 'cltbld', 05:06:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695', 05:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:59 INFO - '_': '/tools/buildbot/bin/python'} 05:06:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 05:06:59 INFO - mozversion application_buildid: 20160918000553 05:06:59 INFO - mozversion application_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 05:06:59 INFO - mozversion application_display_name: Nightly 05:06:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:59 INFO - mozversion application_name: Firefox 05:06:59 INFO - mozversion application_remotingname: firefox 05:06:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:59 INFO - mozversion application_vendor: Mozilla 05:06:59 INFO - mozversion application_version: 51.0a1 05:06:59 INFO - mozversion platform_buildid: 20160918000553 05:06:59 INFO - mozversion platform_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 05:06:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:59 INFO - mozversion platform_version: 51.0a1 05:06:59 INFO - using testdate: 1474200419 05:06:59 INFO - actual date: 1474200419 05:06:59 INFO - starting webserver on 'localhost:53693' 05:06:59 INFO - SUITE-START | Running 2 tests 05:06:59 INFO - TEST-START | dromaeo_css 05:06:59 INFO - operating with platform_type : linux_ 05:06:59 INFO - Initialising browser for dromaeo_css test... 05:06:59 INFO - TEST-INFO | started process 31802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ4jBho/profile http://localhost:53693/getInfo.html) 05:07:03 INFO - PROCESS | 31802 | __metrics Screen width/height:1600/1200 05:07:03 INFO - PROCESS | 31802 | colorDepth:24 05:07:03 INFO - PROCESS | 31802 | Browser inner width/height: 1024/697 05:07:03 INFO - PROCESS | 31802 | __metrics 05:07:04 INFO - PROCESS | 31802 | 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" 05:07:04 INFO - PROCESS | 31802 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:04 INFO - PROCESS | 31802 | console.error: PushService: 05:07:04 INFO - PROCESS | 31802 | stateChangeProcessEnqueue: Error transitioning state 05:07:04 INFO - PROCESS | 31802 | UnknownError 05:07:05 INFO - TEST-INFO | 31802: exit 0 05:07:05 INFO - Browser initialized. 05:07:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:05 INFO - TEST-INFO | started process 31883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ4jBho/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:17 INFO - PROCESS | 31883 | RSS: Main: 174813184 05:07:17 INFO - PROCESS | 31883 | 05:07:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.13335 seconds. 05:07:27 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:07:43 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:07:47 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:07:49 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:07:51 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:08:06 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:08:08 INFO - PROCESS | 31883 | 1474200488798 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] 05:08:14 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 05:08:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 05:08:35 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 05:08:41 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 05:08:43 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.18339 seconds. 05:08:52 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:08:55 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:09:13 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:09:15 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:09:44 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:09:47 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:09:50 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:09:52 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:09:54 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:09:56 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:09:59 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.08339 seconds. 05:10:12 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:10:14 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:10:22 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53693/tests/dromaeo/cssquery-ext.html) 05:10:23 INFO - PROCESS | 31883 | RSS: Main: 292409344 05:10:23 INFO - PROCESS | 31883 | 05:10:26 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:10:38 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:10:42 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:10:52 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:11:06 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:11:12 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:11:22 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:11:36 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:11:40 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:11:42 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:11:55 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:11:59 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:12:01 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:12:03 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:12:19 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:12:24 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:12:29 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:12:31 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:12:33 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 05:12:49 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:12:59 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:13:03 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:13:10 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:13:13 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:13:14 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:13:16 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:13:26 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:13:26 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-ext.html (next: http://localhost:53693/tests/dromaeo/cssquery-jquery.html) 05:13:26 INFO - PROCESS | 31883 | RSS: Main: 271081472 05:13:26 INFO - PROCESS | 31883 | 05:13:30 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:13:45 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:13:49 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:14:01 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:14:03 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:14:13 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:14:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:14:33 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:14:37 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:14:39 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:14:44 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:14:56 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:15:00 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:02 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:15:09 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:15:16 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:15:20 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:23 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:24 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:15:32 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:34 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:48 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:15:50 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:15:52 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:56 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:16:00 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:16:08 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:16:22 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:16:23 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:16:29 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:16:29 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53693/tests/dromaeo/cssquery-mootools.html) 05:16:29 INFO - PROCESS | 31883 | RSS: Main: 218865664 05:16:29 INFO - PROCESS | 31883 | 05:16:58 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:17:00 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:17:02 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:17:16 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:17:18 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:17:20 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:17:38 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:17:42 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:17:46 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:17:59 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:18:01 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:18:05 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:18:23 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:18:25 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:18:41 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:18:45 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:18:47 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:18:55 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:19:05 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:19:07 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:19:15 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:19:17 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:19:19 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:19:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:19:25 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:19:27 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:19:29 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:19:32 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53693/tests/dromaeo/cssquery-prototype.html) 05:19:32 INFO - PROCESS | 31883 | RSS: Main: 218673152 05:19:32 INFO - PROCESS | 31883 | 05:19:36 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 05:19:49 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00127 seconds. 05:20:03 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:20:10 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:20:38 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:20:40 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:20:41 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 2.0001 seconds. 05:20:42 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:20:44 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:20:47 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:20:50 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:21:16 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:18 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:20 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:22 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:21:23 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 05:21:24 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:26 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:21:28 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:31 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:21:33 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:21:36 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:21:39 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:43 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:21:46 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:21:47 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:21:51 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:21:55 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:21:57 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:22:00 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:22:01 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 05:22:06 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:22:07 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:22:13 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:22:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:22:25 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:22:27 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:22:35 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 05:22:35 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53693/tests/dromaeo/cssquery-yui.html) 05:22:36 INFO - PROCESS | 31883 | RSS: Main: 218832896 05:22:36 INFO - PROCESS | 31883 | 05:22:39 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 05:22:41 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:22:42 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:23:03 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:23:07 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:23:09 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:23:17 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:23:21 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:23:23 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:23:25 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:23:34 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:23:38 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 05:23:43 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:23:51 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:24:05 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:24:06 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 05:24:12 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:24:20 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:24:36 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:24:40 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 05:24:46 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:48 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:25:00 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:25:02 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:25:10 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:25:19 INFO - PROCESS | 31883 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:25:39 INFO - PROCESS | 31883 | Cycle 1(1): loaded http://localhost:53693/tests/dromaeo/cssquery-yui.html (next: http://localhost:53693/tests/dromaeo/cssquery-dojo.html) 05:25:39 INFO - PROCESS | 31883 | RSS: Main: 313888768 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | __start_tp_report 05:25:39 INFO - PROCESS | 31883 | _x_x_mozilla_page_load 05:25:39 INFO - PROCESS | 31883 | _x_x_mozilla_page_load_details 05:25:39 INFO - PROCESS | 31883 | |i|pagename|runs| 05:25:39 INFO - PROCESS | 31883 | |0;dojo.html;2569.383347996481;2590.2229331200633;2590.2358804152773;2618.6988496322915;2634.301909993852;4552.658550608709;4582.931256031162;4701.03628756105;4725.551072648097;4736.455307639622;4614.976925115369;4632.976835115828;4636.350910872472;4638.953610463895;4640.466346370168;4245.108527209282;4298.247806633843;4306.7631280279575;4344.97827510862;4364.367166760818;4250.917050645193;4296.957030429702;4330.458692663417;4332.0469496710675;4388.144311859189;4302.956970430287;4328.740275583459;4385.320275357309;4449.710768800018;4455.309427038814;4363.930836944937;4380.408644832939;4412.889677758049;4455.2203364411425;4466.196084704752;4143.647789937859;4174.478190226222;4176.081262122329;4233.724807887508;4294.162638285505;4613.838515651936;4623.121606894713;4625.468071171791;4630.398048253739;4634.629229661619;4879.072976134523;4960.355153829979;4985.725785081819;5003.799848006075;5111.003354345904;4918.803247870081;4953.331300274453;4981.526754958273;5022.4224214215465;5024.62315326352;4681.6722829401615;4681.882952926136;4686.484486706459;4739.431268247832;4740.19416699162;5003;5057.772400241998;5066.392032955995;5073.137566613685;5123.2827404163445;4864.837905236907;4902.50974902514;4906.705597664151;4908.901821963541;4960.578350840219;4987.376577927759;4993.251012348177;4998.625103117281;5002.449730529639;5041.823536176216;4790.5448982346625;4835.975820120877;4906.533879281463;4925.162722337212;5005.524475174852;4954.083494553519;4964.652474326763;4969.2546118081855;4997.825076122318;5027.874303142451;4192.643625291823;4193.874183774492;4197.811098500575;4238.449001629769;4245.724027938174;3588.659077387644;3588.69496092835;3614.222942067468;3662.9816850915577;3716.8141592920247;4182.1844740275355;4213.599708027732;4216.557261487561;4249.553796851348;4262.360645903139;5015.1975683890405;5018.322526458955;5027.4469808321055;5044.798208071636;5076.2131869559535;4210.221109094828;4212.894677633084;4213.431186789744;4216.177845320194;4275.273203555403;4314.439122914001;4355.499117601453;4371.519132895379;4476.552344765562;4510.75190864499;4788.209945358977;4835.323054772264;4844.467108617979;4845.2732090186755;4847.636427267898;5087.262346959744;5092.185250359925;5096.592272618256;5096.719680417613;5156;4597.563231493003;4716.316134160596;4774.880627984189;4777.21176005969;4829.227323628203;4822.493638167942;4920;4948.307236986752;4971.37857767779;5041.924371134362;5065.721385323843;5087.491250874883;5088.516590923857;5096.541311282002;5143.768530416065;4932.161532539549;4932.358793356841;4934.407871055496;4934.629902757236;4983.25251212321;4733.006068725606;4789.784459699392;4856.174450343379;4935.037667654773;4990.700557966534;4818.012307477029;4831.73425461603;4835.879103022312;4898;4903.60771138301;4930.876728081683;4942.184539550934;4942.747424508072;4944.826931057396;5010.248462730619;4670.743109129031;4752.572268495852;4821.927671085006;4839.986655180783;4842.104210721028 05:25:39 INFO - PROCESS | 31883 | |1;ext.html;561.1834780394526;571.4143003421492;572.4847637126586;575.8186171356023;581.9883602327956;27952.301192470197;38468.80765596172;39973;40036.199276014464;43876.780616096876;39106.80446597763;39570;39572.604273957324;39612.40581391282;39638.80180599093;7672.961635191817;7763.456558040939;7781.2996830285265;7818.33544148748;7823.139454659983;7533.397328213757;7617.428692848031;7623;7623;7628.504147230412;7634.465587408884;7676.5394076355315;7677.0403699537555;7682.577458239794;7683.423743219253;7616.390688744915;7649.6940122395035;7650.38796896247;7657.617119144021;7660.961695191516;7601.923980760177;7655.119661238973;7668.578228197447;7669.233076692342;7676.424268179909;37638.247235055416;37981;38001.23997520034;38002.42996355057;38057.809710951544;2942;3150.6376766671897;3531.7174626029855;3574.6067932527403;3575.106223444139;27659.861700691366;27759.444811103866;28325.150245492667;28423.289417764514;29897.402051958838;3291.687289707474;3303.9834800826084;3316.93366132676;3362.2939182771647;3454.4990976308545;254556.27218639178;255735;255942;256546;258224.7088764563;53221.46778532165;53608.46391536113;53617.731911340976;53687.73156134273;53715.462845371825;32425.189370265933;33425.49861752076;33515;35438;51037.48962510328;14597.78103328451;14768.529395592028;14776.926115369355;14781;14815.777763333559;11118.388488633042;11158.1631377647;11185.105191584653;11204.66386008421;11219.214654974237;17446.912765436344;17464.563385915455;17536.824631753774;17543.385981490588;17802.643947121243;6732.2931092235585;6832.419244364185;6856.794296171123;6877.862442751117;6891.586504809728;802.863513202757;870.0646804684989;873.8645509945968;938.4932136646134;947.4883562876107;26935;27116.1865144046;27297.727022729916;27351;27379.315517111834;899.6806133822399;901.6979311930446;928.1732854852597;966.7341481092615;1043.5199808088364;14514.854851451562;14534;14709;14745.705085898222;14865.554033379016;380860;383758;384951;385126;385300;47270.5272947273;47865.76067119712;48078.278825817644;48133.5186648129;48269.275960860614;1072.3619446429361;1115.1803424482998;1151.0963893343685;1163.168334640736;1206.481213078367;24964.37589060288;28496.85751571229;28547.857260714394;28854.278643034093;29318.560221596268;39759;40194;40194.19611607693;40234;40287.3956890641;279218;280333;281465;281495;281793;21905.671414929107;22610.60840873882;24124.105107387226;24884.37789055288;25043.624345635195;38871;39345;39431;39437.80281098576;39870.40194397145;34254.48618270778;34319.485207722406;34620.30759384848;34761.47857782186;35185.64814351926;33835.492467612516;33913;33955.3208935815;33987;34778.652213477544;34528.65471345355;35206.29587408289;35294.64705352914;37375;37877.81061094677;30149.849250753607;31479.68520314859;32993;33038.83480582582;33108.50337244895;24829.25512234636;24893.128740493994;25055;25070.74929250684;25353.239402817944 05:25:39 INFO - PROCESS | 31883 | |2;jquery.html;369.5676059010957;373.7682636765205;378.081262532047;385.40647402999383;393.8444314495774;15587.454439094634;16807.91596042021;17370.478885633434;17481.41630965652;17490.475285741417;16595.253213605418;17212.483625491222;17398.30406783727;17481.650366992653;17489.475315740547;4917.4344950330815;5201.973990130044;5203.427622961472;5206.609504287175;5208.244804503344;3686.668199862012;3792.677622402099;3958.7624742515395;4028.2346354192755;5121.257417674435;6059.757609695629;6189.226346706661;6196.504279657616;6204.162438070848;6205.472534834545;5212.973935130319;5471.917921231185;5493.450654934514;6200.968995155018;6206.317305096436;5403.189521571757;5403.78384864605;5417.187421886709;5422.267993820863;5422.647527910713;11450.370229637367;11673.941630291794;11831.704207394798;11862.169648124634;12204.145709800317;1772.7340898865273;1828.7074068149166;1926.5761532462836;2130.286354071388;2218.8557743746605;8660.523671198082;9138.862917056249;9147.588358523815;11674.299542027506;12549.1215614907;1640.3192675039845;1697.4737831272346;1699.6770613583503;1747.6417334446407;1754.324585034758;70164;72167.63916180439;73833.26166738372;74579.25420745778;76968.23031769667;10032.347897386793;10133.240006999504;10198.643047493302;10374.792504150026;10502.527386267637;11197.49611267495;14176.362063707158;14195.219262940638;14218;14227.78658320126;6457.967710161419;6783.118194634666;6828.385445309946;7274.709011639581;7299.5255308404785;6331.778387756406;6436.645984470899;6488.870222595521;6595.274519802818;6867.862642747217;3776.9433458498142;3799.1831756172555;3864.246471937945;3900.1419687668667;3912.0415498203124;1776.8578513718871;1832.7250912363252;1861.8789778664343;1867.570458794485;1879.7556317678889;769.2384539306158;870.5125129927261;903.2098153426695;912.0378001208788;955.4506158958627;16176.75734863978;16913.154342282836;17128.886622369508;17627.383041593483;17848.643027139384;808.1796976069294;824.6824972385675;827.9089300176976;858.5878778186506;897.4077109107957;9399;9429.245660347156;9852.802943941224;10032.749181270527;10164.237682173867;276054;279510;280003;387889;390664;73238;77798.61100694574;79177;80920.59539702382;80952;923.3767207135157;933.8225737109927;966.2366730283138;974.3179774158118;975.3075316525346;15847.207639617742;15936.760948585548;16032.358705651643;16669.583260418585;17008.659826803643;36720.26559468742;37471.625283747904;38051.80974095222;38712;38752;48112.518874810805;49674;50969;51412;52531.73734131305;7628.885566716614;7764.145943946049;7790.182030886675;7908.802279942817;7978.202179781975;9117.863232051392;11803.23278986863;11833.230839995716;11871.228370155912;12619;9944.701658950244;10080.243981701255;10627.840582391116;10709.892901071202;10883.292585981886;9590.08894155054;9938.503074846085;9968.202543796624;10229.948850255701;10696.03735663794;10062.295639305179;10556.208284378856;10584.523696433833;10873.238873279111;10916.345019298868;6749.662516874235;6923.757668481579;7007.369336759716;7390;7570.242975702386;8865.68970086044;10705.250632455653;10708.357498550113;10977.451127443435;11105.05607023412 05:25:39 INFO - PROCESS | 31883 | |3;mootools.html;1236.4559593778731;1349.838019437667;1381.2058066611698;1427.6644988427718;1678.370611020868;6569.704363303651;7504.099508059034;7767.262110099546;7774.766756997285;8019.198080191978;7863.685452581904;7923.366130709529;7929.326007289373;7932.524048557077;7933.484323518967;2178.313831143185;2204.515006698524;2205.3604454708197;2208.182972300251;2209.86740795552;2162.3945295317258;2202.3723238876923;2233.419310979149;2233.9888300558473;2236.5303286309895;2156.3746513511064;2164.437246315961;2165.372042107787;2173.4892300309416;2228.565429741201;2141.2933731868447;2155.698202251686;2155.795199456049;2155.8814265215565;2158.7625361210253;1961.5390383260064;2010.6179825833044;2015.3147929703825;2016.4757163137542;2024.2307922989316;7660.502067365604;7740.380769538423;7753.108392534874;7754.573498457583;7873.173316801711;4337.82648694055;4478.731276123443;4488.551144885518;4518.977405112953;4561.726296422192;2725.8637068146513;2829.6462942132234;2865.398266364066;2872.8707208175683;2877.007432435806;1006.4414250091196;1009.4094954451655;1027.2587963402036;1029.7219750667277;1044.7753732947376;10828.837567436416;10946.56213751449;10948.178886583539;10966.725831854188;10977.28647637901;4007.719459637797;4097.713160078825;4262.637675797592;4271.615554600101;4311.159323931866;8521.786955326166;8585.141485851489;8606.225439710455;8606.44058136219;8610.225079742853;4359.825606975749;4375.190589740908;4406.889827754332;4411.09572537629;4419.64642828573;3495.720342372604;3692.261547690419;3776.1126135358168;4087.9795601021806;4092.7135100543273;3295.0938491914917;3439.5528581284275;3446.6897979181495;3453.3064209916306;3475.5323720158467;1041.5417216424748;1041.5885725138528;1052.400131924811;1058.9947050264852;1061.3101484035285;637.9266384365765;699.2657709405104;724.0225695311393;751.2262369759156;759.4456047085658;4761.40482439695;4974.32846565709;5056.924146137811;5096.9490305097215;5161.974190129025;407.6046235151898;409.58018031517935;413.0808950086035;430.34587427110614;435.9934600980988;3251.4472539668313;3289.786163899339;3313.2710803623163;3323.4350160472777;3349.7487688423466;9215.078492150866;9491.288153388385;9571.138597526256;9578.281628877861;9604.567794449267;9498.95250523743;9503.57233924475;9510.09654082861;9529.714108576754;9539.666111686196;7084.716611335489;7095.361417472349;7135.429165666734;7144.535605185646;7144.714211431485;4814.229723244264;4874.805007799649;4888.877778055577;4947.826826061215;5055.317532133262;8501.914980850112;8600.827983440422;8683.565821708762;8732.170443808081;8829.293656507336;8037.598120094089;8804.295656347349;8887.866681999645;8889.19997200255;8915.509646969478;5738.913916291175;6160.168377269011;6299.590526615755;6324.494040476843;6503.154589903283;7459.813504662427;7470.551766893786;7475.514091584029;7550.811229719081;7553.093628764584;6757.45940324763;6857.142857142857;6904.27505111956;6928.826779330557;6977.1278590176225;6327.905081423874;6456.160699109274;6457.257415397289;6484.610923344614;6488.708008139551;5702.657840529581;5820.214271073351;5886.087656413263;5888.676122813286;6014.8195554133445;4263.126059157803;4346.891327716706;4413.117376524772;4448;4577.610903073276;4511.639068874548;4532.954670453385;4548.6361091113295;4614.884627884329;4626.467956184947 05:25:39 INFO - PROCESS | 31883 | |4;prototype.html;304.06651579650475;304.844529290062;309.1529209964697;311.88460269700215;312.048252828872;4744.976275118624;4817.421909370872;4817.711586996179;4822;4850.4179498460235;4710.764461776915;4729.810807567693;4779.139754844126;4794.784234709437;4800.255960326146;3521.6830485256323;3638.435472746718;3653.6163702811223;3706.5737440194316;3713.6657700806923;3584.13980644646;3716.8513259469587;3725.739198256123;3729.552453705559;3747.4004159334513;3679.7056235501095;3681.6686498215154;3716.981415092934;3720.6465385788306;3730.451862477082;3620.7827530348263;3707.4068149096015;3724.8510059597584;3724.8639165054647;3729.1609387887506;3281.196106953788;3283.1928700960575;3288.7338374725905;3306.206510437502;3306.35526072416;4569.977150114228;4575.816967321303;4587.816487340502;4591.403117594725;4601.5168407317415;4234.9364759528635;4240.787960601958;4261.423273388835;4277.593628605277;4282.785860706983;4744.762761861927;4882.048000639877;4895.241237608177;4920.458749537548;4929.338157395003;3597.874074407383;3604.981975090108;3605.206854492034;3605.441156620728;3605.94591081134;5154.252633368141;5161;5185.196294574347;5205.167173252299;5208.479152084799;3442.6901578858024;3633.981830090833;3713.1830997180577;3719.442083687414;3721.846227669377;3669.4679271506016;3680.1351682354625;3688.8340024698955;3693.741438099361;3694.261147770457;4202.411662367271;4215.388768628532;4217.1144059747185;4217.283061879488;4228.809703563347;5018.272350509155;5027.648064635514;5028.34631497903;5049.116404629175;5053.267276244924;1211.794264706605;1266.9809952850715;1269.9746005079846;1270.9110362274553;1280.8591054983945;794.3605397654943;813.3980854167874;828.5981299069946;835.1690068381998;836.93304535637;3903.726739128234;4109.958900411018;4212.410262563244;4248.4477017987465;4318.805653745589;5068.797248110108;5091.66904151229;5097.796088156507;5109.923351149736;5121.513456221653;4292.763897985578;4305.461817272841;4308.9353659695125;4316.935245971313;4318.222719910384;2592.325995241251;2661.9068332608654;2673.5187666220268;2686.597010448448;2696.258528904567;4780.25905984566;4793.257045158006;4797;4814.398200224972;4819.795051237191;5185.211102169746;5194.168932970707;5199.844004679866;5214.556762675139;5220.895582088337;4622.976885115553;4817.301491283814;4879.658423910292;4907.77914993819;4922.5569698728;5126.589872810092;5155.458676838878;5159.509846564571;5164.870878228075;5173.4826517347965;5058.64589478733;5080.26336181259;5083;5115.846524604268;5117.641765076558;4709.1398897436;4821.807127714852;4846.39420072491;4861.854144375674;4879.268109783419;3615.837287322024;3651.3610118229735;3671;3675.4746780085966;3731.272401881607;5219;5227.320448341691;5227.529522343008;5230.450802665665;5257.8159764408065;4806.519348065165;5002.299678045004;5009.223570346602;5014.674046187132;5057.811414317706;4999.925001124913;5001.274815151855;5001.924971125509;5005.674631148963;5019.472955339636;4957.8844759928725;4975.950240497548;4981.687178026572;4984.37695288089;5002.549770520671;4816.879578010437;4906.239532872411;5028.421731500925;5044.267242240789;5053.8881446081805;4776.307435421914;4845.9757701211265;4914.238293064581;4916.164252077227;4929.433115191799 05:25:39 INFO - PROCESS | 31883 | |5;yui.html;599.8542783283929;611.2786911444493;618.9195404597401;692.2973182220046;723.3778950102908;12542.247465152086;13009.73980520389;13044.543440979567;13048.608541743763;13052.28212448315;13304.93347533261;13398.062135650509;13406.865931340684;13418.396172172252;13460.932695336534;619.3868070610087;633.0883527720091;740.6778051547567;745.7129005332931;767.7965339185121;777.5218240781907;779.9181085985975;780.9062912450514;782.5774081995744;783.2089589514603;815.8980127484065;819.6557445872747;819.7458787775779;819.815541503163;820.1798201798201;639.83684160539;651.5439192565224;662.4865729059969;664.6344510519195;816.7753867686404;486.48918635432653;578.1674388879999;590.4833270887973;590.7873165660359;598.0341748076868;12061.577844775391;12073.396330183456;12083.758324833541;12402.503899844085;12453.315067671274;12386.442610082568;12599.93700031503;12976.41606127717;13149.802752958618;13587.456501740018;11427.257228280136;11597.130215233807;11748.882511174865;11931.224470409483;12430.689232769162;3989.6608788253034;3997.4603428537066;4030.234255491447;4103.671706263492;4136.834526618932;63256.68371658158;63504;64634.67682661556;64844.351556484304;65455.67272163656;9270.536473176315;9428.57142857143;9483.336166468282;9558.091981261769;9784.266180036526;15931.522054338388;15959.601009974844;15964.600884977968;16069.678606427806;16088.356465741239;6760;6778.356056174655;6786.219584747719;6787.966060169766;6794.11676482064;3793.1804091754493;3801.8099095045136;3830.942535861964;3904.902377440587;3913.8238779255003;2739.4247208086126;2829.462402143586;2844.815087019337;2849.244950088228;2851.1731597836397;473.8483685220696;476.0312763526277;478.258699016523;486.23660852461296;489.1610887328249;457.80543269110495;458.20730136863415;458.5712358944331;459.99080018400116;462.8310666606664;6972.058772065808;7021.473389495808;7485.465479576674;7487.962560187273;8090.197607124642;370.762711864407;405.65519308588057;414.57506056291936;441.3446032641589;447.2396925227127;3430.8627654893526;3466.9479957800654;3571.7499775016017;3577.123604716809;3631.5642122945414;98913;102343;103107;104570;107212;90284.54857725669;93146.53426732958;93660;94683.52658236664;96478.0352196469;78513;78895;80244.19755802368;80428.59785701035;80815;7067.2579379164445;7223.024891639561;7288.526245794006;7410.925890741024;7460.850782984418;45547.31679024751;45934.77032614816;47665;48058.51941480541;48572.27141592808;62749.372506274354;68166;68465.65767171331;68802.65598671975;69784;9289.256859451363;9302.488363140092;9350.81298374015;9533.380330278509;9535.092981403886;9689.30437173506;10020.398776073458;10106.595736170471;10183.18534517222;10276.743081423025;9596.328257021942;9677.274204434554;9715.36850104741;10171.440570768384;10193.133583645176;8706.651733930572;9013;9281.582328795355;9420.434773913586;9484.33609647318;9109.362344635812;9514.762130946781;9635.807283854423;9772.804543909224;9831.557579908778;8310.168983101641;8431.072582015851;8454.154584541497;8709.085546017577;8787.648494060166;7452.739154129579;7813.531188128503;8294.58527073656;8655.133148210918;8928.464292142493 05:25:39 INFO - PROCESS | 31883 | __end_tp_report 05:25:39 INFO - PROCESS | 31883 | __start_cc_report 05:25:39 INFO - PROCESS | 31883 | _x_x_mozilla_cycle_collect,1032 05:25:39 INFO - PROCESS | 31883 | __end_cc_report 05:25:39 INFO - PROCESS | 31883 | __startTimestamp1474201539527__endTimestamp 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | ------- Summary: start ------- 05:25:39 INFO - PROCESS | 31883 | Number of tests: 6 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#0] dojo.html Cycles:165 Average:4622.38 Median:4788.21 stddev:499.03 (10.4%) stddev-sans-first:473.86 05:25:39 INFO - PROCESS | 31883 | Values: 2569.4 2590.2 2590.2 2618.7 2634.3 4552.7 4582.9 4701.0 4725.6 4736.5 4615.0 4633.0 4636.4 4639.0 4640.5 4245.1 4298.2 4306.8 4345.0 4364.4 4250.9 4297.0 4330.5 4332.0 4388.1 4303.0 4328.7 4385.3 4449.7 4455.3 4363.9 4380.4 4412.9 4455.2 4466.2 4143.6 4174.5 4176.1 4233.7 4294.2 4613.8 4623.1 4625.5 4630.4 4634.6 4879.1 4960.4 4985.7 5003.8 5111.0 4918.8 4953.3 4981.5 5022.4 5024.6 4681.7 4681.9 4686.5 4739.4 4740.2 5003.0 5057.8 5066.4 5073.1 5123.3 4864.8 4902.5 4906.7 4908.9 4960.6 4987.4 4993.3 4998.6 5002.4 5041.8 4790.5 4836.0 4906.5 4925.2 5005.5 4954.1 4964.7 4969.3 4997.8 5027.9 4192.6 4193.9 4197.8 4238.4 4245.7 3588.7 3588.7 3614.2 3663.0 3716.8 4182.2 4213.6 4216.6 4249.6 4262.4 5015.2 5018.3 5027.4 5044.8 5076.2 4210.2 4212.9 4213.4 4216.2 4275.3 4314.4 4355.5 4371.5 4476.6 4510.8 4788.2 4835.3 4844.5 4845.3 4847.6 5087.3 5092.2 5096.6 5096.7 5156.0 4597.6 4716.3 4774.9 4777.2 4829.2 4822.5 4920.0 4948.3 4971.4 5041.9 5065.7 5087.5 5088.5 5096.5 5143.8 4932.2 4932.4 4934.4 4934.6 4983.3 4733.0 4789.8 4856.2 4935.0 4990.7 4818.0 4831.7 4835.9 4898.0 4903.6 4930.9 4942.2 4942.7 4944.8 5010.2 4670.7 4752.6 4821.9 4840.0 4842.1 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#1] ext.html Cycles:180 Average:45491.42 Median:19484.86 stddev:82078.00 (421.2%) stddev-sans-first:82238.92 05:25:39 INFO - PROCESS | 31883 | Values: 561.2 571.4 572.5 575.8 582.0 27952.3 38468.8 39973.0 40036.2 43876.8 39106.8 39570.0 39572.6 39612.4 39638.8 7673.0 7763.5 7781.3 7818.3 7823.1 7533.4 7617.4 7623.0 7623.0 7628.5 7634.5 7676.5 7677.0 7682.6 7683.4 7616.4 7649.7 7650.4 7657.6 7661.0 7601.9 7655.1 7668.6 7669.2 7676.4 37638.2 37981.0 38001.2 38002.4 38057.8 2942.0 3150.6 3531.7 3574.6 3575.1 27659.9 27759.4 28325.2 28423.3 29897.4 3291.7 3304.0 3316.9 3362.3 3454.5 254556.3 255735.0 255942.0 256546.0 258224.7 53221.5 53608.5 53617.7 53687.7 53715.5 32425.2 33425.5 33515.0 35438.0 51037.5 14597.8 14768.5 14776.9 14781.0 14815.8 11118.4 11158.2 11185.1 11204.7 11219.2 17446.9 17464.6 17536.8 17543.4 17802.6 6732.3 6832.4 6856.8 6877.9 6891.6 802.9 870.1 873.9 938.5 947.5 26935.0 27116.2 27297.7 27351.0 27379.3 899.7 901.7 928.2 966.7 1043.5 14514.9 14534.0 14709.0 14745.7 14865.6 380860.0 383758.0 384951.0 385126.0 385300.0 47270.5 47865.8 48078.3 48133.5 48269.3 1072.4 1115.2 1151.1 1163.2 1206.5 24964.4 28496.9 28547.9 28854.3 29318.6 39759.0 40194.0 40194.2 40234.0 40287.4 279218.0 280333.0 281465.0 281495.0 281793.0 21905.7 22610.6 24124.1 24884.4 25043.6 38871.0 39345.0 39431.0 39437.8 39870.4 34254.5 34319.5 34620.3 34761.5 35185.6 33835.5 33913.0 33955.3 33987.0 34778.7 34528.7 35206.3 35294.6 37375.0 37877.8 30149.8 31479.7 32993.0 33038.8 33108.5 24829.3 24893.1 25055.0 25070.7 25353.2 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#2] jquery.html Cycles:180 Average:22417.36 Median:195894.57 stddev:54761.56 (28.0%) stddev-sans-first:54890.16 05:25:39 INFO - PROCESS | 31883 | Values: 369.6 373.8 378.1 385.4 393.8 15587.5 16807.9 17370.5 17481.4 17490.5 16595.3 17212.5 17398.3 17481.7 17489.5 4917.4 5202.0 5203.4 5206.6 5208.2 3686.7 3792.7 3958.8 4028.2 5121.3 6059.8 6189.2 6196.5 6204.2 6205.5 5213.0 5471.9 5493.5 6201.0 6206.3 5403.2 5403.8 5417.2 5422.3 5422.6 11450.4 11673.9 11831.7 11862.2 12204.1 1772.7 1828.7 1926.6 2130.3 2218.9 8660.5 9138.9 9147.6 11674.3 12549.1 1640.3 1697.5 1699.7 1747.6 1754.3 70164.0 72167.6 73833.3 74579.3 76968.2 10032.3 10133.2 10198.6 10374.8 10502.5 11197.5 14176.4 14195.2 14218.0 14227.8 6458.0 6783.1 6828.4 7274.7 7299.5 6331.8 6436.6 6488.9 6595.3 6867.9 3776.9 3799.2 3864.2 3900.1 3912.0 1776.9 1832.7 1861.9 1867.6 1879.8 769.2 870.5 903.2 912.0 955.5 16176.8 16913.2 17128.9 17627.4 17848.6 808.2 824.7 827.9 858.6 897.4 9399.0 9429.2 9852.8 10032.7 10164.2 276054.0 279510.0 280003.0 387889.0 390664.0 73238.0 77798.6 79177.0 80920.6 80952.0 923.4 933.8 966.2 974.3 975.3 15847.2 15936.8 16032.4 16669.6 17008.7 36720.3 37471.6 38051.8 38712.0 38752.0 48112.5 49674.0 50969.0 51412.0 52531.7 7628.9 7764.1 7790.2 7908.8 7978.2 9117.9 11803.2 11833.2 11871.2 12619.0 9944.7 10080.2 10627.8 10709.9 10883.3 9590.1 9938.5 9968.2 10229.9 10696.0 10062.3 10556.2 10584.5 10873.2 10916.3 6749.7 6923.8 7007.4 7390.0 7570.2 8865.7 10705.3 10708.4 10977.5 11105.1 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#3] mootools.html Cycles:180 Average:4995.93 Median:4555.18 stddev:2870.95 (63.0%) stddev-sans-first:2865.10 05:25:39 INFO - PROCESS | 31883 | Values: 1236.5 1349.8 1381.2 1427.7 1678.4 6569.7 7504.1 7767.3 7774.8 8019.2 7863.7 7923.4 7929.3 7932.5 7933.5 2178.3 2204.5 2205.4 2208.2 2209.9 2162.4 2202.4 2233.4 2234.0 2236.5 2156.4 2164.4 2165.4 2173.5 2228.6 2141.3 2155.7 2155.8 2155.9 2158.8 1961.5 2010.6 2015.3 2016.5 2024.2 7660.5 7740.4 7753.1 7754.6 7873.2 4337.8 4478.7 4488.6 4519.0 4561.7 2725.9 2829.6 2865.4 2872.9 2877.0 1006.4 1009.4 1027.3 1029.7 1044.8 10828.8 10946.6 10948.2 10966.7 10977.3 4007.7 4097.7 4262.6 4271.6 4311.2 8521.8 8585.1 8606.2 8606.4 8610.2 4359.8 4375.2 4406.9 4411.1 4419.6 3495.7 3692.3 3776.1 4088.0 4092.7 3295.1 3439.6 3446.7 3453.3 3475.5 1041.5 1041.6 1052.4 1059.0 1061.3 637.9 699.3 724.0 751.2 759.4 4761.4 4974.3 5056.9 5096.9 5162.0 407.6 409.6 413.1 430.3 436.0 3251.4 3289.8 3313.3 3323.4 3349.7 9215.1 9491.3 9571.1 9578.3 9604.6 9499.0 9503.6 9510.1 9529.7 9539.7 7084.7 7095.4 7135.4 7144.5 7144.7 4814.2 4874.8 4888.9 4947.8 5055.3 8501.9 8600.8 8683.6 8732.2 8829.3 8037.6 8804.3 8887.9 8889.2 8915.5 5738.9 6160.2 6299.6 6324.5 6503.2 7459.8 7470.6 7475.5 7550.8 7553.1 6757.5 6857.1 6904.3 6928.8 6977.1 6327.9 6456.2 6457.3 6484.6 6488.7 5702.7 5820.2 5886.1 5888.7 6014.8 4263.1 4346.9 4413.1 4448.0 4577.6 4511.6 4533.0 4548.6 4614.9 4626.5 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#4] prototype.html Cycles:180 Average:4137.35 Median:4760.64 stddev:1207.71 (25.4%) stddev-sans-first:1176.33 05:25:39 INFO - PROCESS | 31883 | Values: 304.1 304.8 309.2 311.9 312.0 4745.0 4817.4 4817.7 4822.0 4850.4 4710.8 4729.8 4779.1 4794.8 4800.3 3521.7 3638.4 3653.6 3706.6 3713.7 3584.1 3716.9 3725.7 3729.6 3747.4 3679.7 3681.7 3717.0 3720.6 3730.5 3620.8 3707.4 3724.9 3724.9 3729.2 3281.2 3283.2 3288.7 3306.2 3306.4 4570.0 4575.8 4587.8 4591.4 4601.5 4234.9 4240.8 4261.4 4277.6 4282.8 4744.8 4882.0 4895.2 4920.5 4929.3 3597.9 3605.0 3605.2 3605.4 3605.9 5154.3 5161.0 5185.2 5205.2 5208.5 3442.7 3634.0 3713.2 3719.4 3721.8 3669.5 3680.1 3688.8 3693.7 3694.3 4202.4 4215.4 4217.1 4217.3 4228.8 5018.3 5027.6 5028.3 5049.1 5053.3 1211.8 1267.0 1270.0 1270.9 1280.9 794.4 813.4 828.6 835.2 836.9 3903.7 4110.0 4212.4 4248.4 4318.8 5068.8 5091.7 5097.8 5109.9 5121.5 4292.8 4305.5 4308.9 4316.9 4318.2 2592.3 2661.9 2673.5 2686.6 2696.3 4780.3 4793.3 4797.0 4814.4 4819.8 5185.2 5194.2 5199.8 5214.6 5220.9 4623.0 4817.3 4879.7 4907.8 4922.6 5126.6 5155.5 5159.5 5164.9 5173.5 5058.6 5080.3 5083.0 5115.8 5117.6 4709.1 4821.8 4846.4 4861.9 4879.3 3615.8 3651.4 3671.0 3675.5 3731.3 5219.0 5227.3 5227.5 5230.5 5257.8 4806.5 5002.3 5009.2 5014.7 5057.8 4999.9 5001.3 5001.9 5005.7 5019.5 4957.9 4976.0 4981.7 4984.4 5002.5 4816.9 4906.2 5028.4 5044.3 5053.9 4776.3 4846.0 4914.2 4916.2 4929.4 05:25:39 INFO - PROCESS | 31883 | 05:25:39 INFO - PROCESS | 31883 | [#5] yui.html Cycles:180 Average:18075.77 Median:31944.89 stddev:27434.85 (85.9%) stddev-sans-first:27480.43 05:25:39 INFO - PROCESS | 31883 | Values: 599.9 611.3 618.9 692.3 723.4 12542.2 13009.7 13044.5 13048.6 13052.3 13304.9 13398.1 13406.9 13418.4 13460.9 619.4 633.1 740.7 745.7 767.8 777.5 779.9 780.9 782.6 783.2 815.9 819.7 819.7 819.8 820.2 639.8 651.5 662.5 664.6 816.8 486.5 578.2 590.5 590.8 598.0 12061.6 12073.4 12083.8 12402.5 12453.3 12386.4 12599.9 12976.4 13149.8 13587.5 11427.3 11597.1 11748.9 11931.2 12430.7 3989.7 3997.5 4030.2 4103.7 4136.8 63256.7 63504.0 64634.7 64844.4 65455.7 9270.5 9428.6 9483.3 9558.1 9784.3 15931.5 15959.6 15964.6 16069.7 16088.4 6760.0 6778.4 6786.2 6788.0 6794.1 3793.2 3801.8 3830.9 3904.9 3913.8 2739.4 2829.5 2844.8 2849.2 2851.2 473.8 476.0 478.3 486.2 489.2 457.8 458.2 458.6 460.0 462.8 6972.1 7021.5 7485.5 7488.0 8090.2 370.8 405.7 414.6 441.3 447.2 3430.9 3466.9 3571.7 3577.1 3631.6 98913.0 102343.0 103107.0 104570.0 107212.0 90284.5 93146.5 93660.0 94683.5 96478.0 78513.0 78895.0 80244.2 80428.6 80815.0 7067.3 7223.0 7288.5 7410.9 7460.9 45547.3 45934.8 47665.0 48058.5 48572.3 62749.4 68166.0 68465.7 68802.7 69784.0 9289.3 9302.5 9350.8 9533.4 9535.1 9689.3 10020.4 10106.6 10183.2 10276.7 9596.3 9677.3 9715.4 10171.4 10193.1 8706.7 9013.0 9281.6 9420.4 9484.3 9109.4 9514.8 9635.8 9772.8 9831.6 8310.2 8431.1 8454.2 8709.1 8787.6 7452.7 7813.5 8294.6 8655.1 8928.5 05:25:39 INFO - PROCESS | 31883 | -------- Summary: end -------- 05:25:39 INFO - PROCESS | 31883 | 05:25:40 INFO - TEST-INFO | 31883: exit 0 05:25:40 INFO - TEST-OK | dromaeo_css | took 1121346ms 05:25:40 INFO - TEST-START | kraken 05:25:40 INFO - operating with platform_type : linux_ 05:25:40 INFO - Initialising browser for kraken test... 05:25:40 INFO - TEST-INFO | started process 32192 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEBYdhH/profile http://localhost:53693/getInfo.html) 05:25:45 INFO - PROCESS | 32192 | __metrics Screen width/height:1600/1200 05:25:45 INFO - PROCESS | 32192 | colorDepth:24 05:25:45 INFO - PROCESS | 32192 | Browser inner width/height: 1024/697 05:25:45 INFO - PROCESS | 32192 | __metrics 05:25:45 INFO - PROCESS | 32192 | 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" 05:25:45 INFO - PROCESS | 32192 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:46 INFO - PROCESS | 32192 | console.error: PushService: 05:25:46 INFO - PROCESS | 32192 | stateChangeProcessEnqueue: Error transitioning state 05:25:46 INFO - PROCESS | 32192 | UnknownError 05:25:46 INFO - TEST-INFO | 32192: exit 0 05:25:46 INFO - Browser initialized. 05:25:46 INFO - Running cycle 1/1 for kraken test... 05:25:46 INFO - TEST-INFO | started process 32274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEBYdhH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:59 INFO - PROCESS | 32274 | RSS: Main: 174698496 05:25:59 INFO - PROCESS | 32274 | 05:26:01 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53693/tests/kraken/driver.html?testName=audio-beat-detection) 05:26:02 INFO - PROCESS | 32274 | RSS: Main: 242135040 05:26:02 INFO - PROCESS | 32274 | 05:26:05 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53693/tests/kraken/driver.html?testName=audio-dft) 05:26:06 INFO - PROCESS | 32274 | RSS: Main: 217915392 05:26:06 INFO - PROCESS | 32274 | 05:26:09 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53693/tests/kraken/driver.html?testName=audio-fft) 05:26:10 INFO - PROCESS | 32274 | RSS: Main: 300507136 05:26:10 INFO - PROCESS | 32274 | 05:26:12 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53693/tests/kraken/driver.html?testName=audio-oscillator) 05:26:12 INFO - PROCESS | 32274 | RSS: Main: 210030592 05:26:12 INFO - PROCESS | 32274 | 05:26:15 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53693/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:15 INFO - PROCESS | 32274 | RSS: Main: 213872640 05:26:15 INFO - PROCESS | 32274 | 05:26:18 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53693/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:19 INFO - PROCESS | 32274 | RSS: Main: 250200064 05:26:19 INFO - PROCESS | 32274 | 05:26:23 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53693/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:23 INFO - PROCESS | 32274 | RSS: Main: 244965376 05:26:23 INFO - PROCESS | 32274 | 05:26:26 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53693/tests/kraken/driver.html?testName=json-parse-financial) 05:26:27 INFO - PROCESS | 32274 | RSS: Main: 247279616 05:26:27 INFO - PROCESS | 32274 | 05:26:28 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53693/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:29 INFO - PROCESS | 32274 | RSS: Main: 210382848 05:26:29 INFO - PROCESS | 32274 | 05:26:31 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:32 INFO - PROCESS | 32274 | RSS: Main: 218259456 05:26:32 INFO - PROCESS | 32274 | 05:26:34 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:34 INFO - PROCESS | 32274 | RSS: Main: 222326784 05:26:34 INFO - PROCESS | 32274 | 05:26:37 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:38 INFO - PROCESS | 32274 | RSS: Main: 223420416 05:26:38 INFO - PROCESS | 32274 | 05:26:41 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:41 INFO - PROCESS | 32274 | RSS: Main: 221245440 05:26:41 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | Cycle 1(1): loaded http://localhost:53693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53693/tests/kraken/driver.html?testName=ai-astar) 05:26:43 INFO - PROCESS | 32274 | RSS: Main: 272580608 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | __start_tp_report 05:26:43 INFO - PROCESS | 32274 | _x_x_mozilla_page_load 05:26:43 INFO - PROCESS | 32274 | _x_x_mozilla_page_load_details 05:26:43 INFO - PROCESS | 32274 | |i|pagename|runs| 05:26:43 INFO - PROCESS | 32274 | |0;ai-astar;113;114;116;115;113;116;115;114;115;114 05:26:43 INFO - PROCESS | 32274 | |1;audio-beat-detection;159;124;128;160;131;123;126;125;153;123 05:26:43 INFO - PROCESS | 32274 | |2;audio-dft;161;161;161;160;162;160;161;161;161;161 05:26:43 INFO - PROCESS | 32274 | |3;audio-fft;75;76;77;109;79;79;76;78;109;77 05:26:43 INFO - PROCESS | 32274 | |4;audio-oscillator;90;84;113;84;83;84;103;82;84;113 05:26:43 INFO - PROCESS | 32274 | |5;imaging-gaussian-blur;117;117;117;116;118;118;118;117;118;117 05:26:43 INFO - PROCESS | 32274 | |6;imaging-darkroom;147;147;147;147;147;148;148;147;147;147 05:26:43 INFO - PROCESS | 32274 | |7;imaging-desaturate;95;95;95;94;95;95;94;95;95;94 05:26:43 INFO - PROCESS | 32274 | |8;json-parse-financial;64;64;62;64;65;65;64;64;64;64 05:26:43 INFO - PROCESS | 32274 | |9;json-stringify-tinderbox;67;66;66;66;66;66;65;65;66;66 05:26:43 INFO - PROCESS | 32274 | |10;stanford-crypto-aes;87;83;83;83;87;83;82;82;82;88 05:26:43 INFO - PROCESS | 32274 | |11;stanford-crypto-ccm;136;134;131;130;130;131;132;133;181;144 05:26:43 INFO - PROCESS | 32274 | |12;stanford-crypto-pbkdf2;180;176;181;173;176;178;178;179;178;178 05:26:43 INFO - PROCESS | 32274 | |13;stanford-crypto-sha256-iterative;69;69;76;68;68;70;69;69;68;68 05:26:43 INFO - PROCESS | 32274 | __end_tp_report 05:26:43 INFO - PROCESS | 32274 | __start_cc_report 05:26:43 INFO - PROCESS | 32274 | _x_x_mozilla_cycle_collect,1911 05:26:43 INFO - PROCESS | 32274 | __end_cc_report 05:26:43 INFO - PROCESS | 32274 | __startTimestamp1474201603965__endTimestamp 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | ------- Summary: start ------- 05:26:43 INFO - PROCESS | 32274 | Number of tests: 14 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:1.08 (0.9%) stddev-sans-first:1.00 05:26:43 INFO - PROCESS | 32274 | Values: 113.0 114.0 116.0 115.0 113.0 116.0 115.0 114.0 115.0 114.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:129.50 stddev:15.56 (12.0%) stddev-sans-first:13.92 05:26:43 INFO - PROCESS | 32274 | Values: 159.0 124.0 128.0 160.0 131.0 123.0 126.0 125.0 153.0 123.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:26:43 INFO - PROCESS | 32274 | Values: 161.0 161.0 161.0 160.0 162.0 160.0 161.0 161.0 161.0 161.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#3] audio-fft Cycles:10 Average:83.50 Median:77.00 stddev:13.50 (17.5%) stddev-sans-first:13.97 05:26:43 INFO - PROCESS | 32274 | Values: 75.0 76.0 77.0 109.0 79.0 79.0 76.0 78.0 109.0 77.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.00 stddev:12.67 (15.1%) stddev-sans-first:13.41 05:26:43 INFO - PROCESS | 32274 | Values: 90.0 84.0 113.0 84.0 83.0 84.0 103.0 82.0 84.0 113.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:26:43 INFO - PROCESS | 32274 | Values: 117.0 117.0 117.0 116.0 118.0 118.0 118.0 117.0 118.0 117.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 05:26:43 INFO - PROCESS | 32274 | Values: 147.0 147.0 147.0 147.0 147.0 148.0 148.0 147.0 147.0 147.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:26:43 INFO - PROCESS | 32274 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 05:26:43 INFO - PROCESS | 32274 | Values: 64.0 64.0 62.0 64.0 65.0 65.0 64.0 64.0 64.0 64.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 05:26:43 INFO - PROCESS | 32274 | Values: 67.0 66.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 66.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.00 stddev:2.36 (2.8%) stddev-sans-first:2.24 05:26:43 INFO - PROCESS | 32274 | Values: 87.0 83.0 83.0 83.0 87.0 83.0 82.0 82.0 82.0 88.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:133.50 stddev:15.60 (11.7%) stddev-sans-first:16.53 05:26:43 INFO - PROCESS | 32274 | Values: 136.0 134.0 131.0 130.0 130.0 131.0 132.0 133.0 181.0 144.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:178.00 stddev:2.26 (1.3%) stddev-sans-first:2.24 05:26:43 INFO - PROCESS | 32274 | Values: 180.0 176.0 181.0 173.0 176.0 178.0 178.0 179.0 178.0 178.0 05:26:43 INFO - PROCESS | 32274 | 05:26:43 INFO - PROCESS | 32274 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:2.41 (3.5%) stddev-sans-first:2.55 05:26:43 INFO - PROCESS | 32274 | Values: 69.0 69.0 76.0 68.0 68.0 70.0 69.0 69.0 68.0 68.0 05:26:43 INFO - PROCESS | 32274 | -------- Summary: end -------- 05:26:43 INFO - PROCESS | 32274 | 05:26:45 INFO - TEST-INFO | 32274: exit 0 05:26:45 INFO - TEST-OK | kraken | took 64751ms 05:26:45 INFO - SUITE-END | took 1186s 05:26:45 INFO - Completed test suite (00:19:46) 05:26:45 INFO - javascript benchmark 05:26:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2569.383347996481, 2590.2229331200633, 2590.2358804152773, 2618.6988496322915, 2634.301909993852, 4552.658550608709, 4582.931256031162, 4701.03628756105, 4725.551072648097, 4736.455307639622, 4614.976925115369, 4632.976835115828, 4636.350910872472, 4638.953610463895, 4640.466346370168, 4245.108527209282, 4298.247806633843, 4306.7631280279575, 4344.97827510862, 4364.367166760818, 4250.917050645193, 4296.957030429702, 4330.458692663417, 4332.0469496710675, 4388.144311859189, 4302.956970430287, 4328.740275583459, 4385.320275357309, 4449.710768800018, 4455.309427038814, 4363.930836944937, 4380.408644832939, 4412.889677758049, 4455.2203364411425, 4466.196084704752, 4143.647789937859, 4174.478190226222, 4176.081262122329, 4233.724807887508, 4294.162638285505, 4613.838515651936, 4623.121606894713, 4625.468071171791, 4630.398048253739, 4634.629229661619, 4879.072976134523, 4960.355153829979, 4985.725785081819, 5003.799848006075, 5111.003354345904, 4918.803247870081, 4953.331300274453, 4981.526754958273, 5022.4224214215465, 5024.62315326352, 4681.6722829401615, 4681.882952926136, 4686.484486706459, 4739.431268247832, 4740.19416699162, 5003.0, 5057.772400241998, 5066.392032955995, 5073.137566613685, 5123.2827404163445, 4864.837905236907, 4902.50974902514, 4906.705597664151, 4908.901821963541, 4960.578350840219, 4987.376577927759, 4993.251012348177, 4998.625103117281, 5002.449730529639, 5041.823536176216, 4790.5448982346625, 4835.975820120877, 4906.533879281463, 4925.162722337212, 5005.524475174852, 4954.083494553519, 4964.652474326763, 4969.2546118081855, 4997.825076122318, 5027.874303142451, 4192.643625291823, 4193.874183774492, 4197.811098500575, 4238.449001629769, 4245.724027938174, 3588.659077387644, 3588.69496092835, 3614.222942067468, 3662.9816850915577, 3716.8141592920247, 4182.1844740275355, 4213.599708027732, 4216.557261487561, 4249.553796851348, 4262.360645903139, 5015.1975683890405, 5018.322526458955, 5027.4469808321055, 5044.798208071636, 5076.2131869559535, 4210.221109094828, 4212.894677633084, 4213.431186789744, 4216.177845320194, 4275.273203555403, 4314.439122914001, 4355.499117601453, 4371.519132895379, 4476.552344765562, 4510.75190864499, 4788.209945358977, 4835.323054772264, 4844.467108617979, 4845.2732090186755, 4847.636427267898, 5087.262346959744, 5092.185250359925, 5096.592272618256, 5096.719680417613, 5156.0, 4597.563231493003, 4716.316134160596, 4774.880627984189, 4777.21176005969, 4829.227323628203, 4822.493638167942, 4920.0, 4948.307236986752, 4971.37857767779, 5041.924371134362, 5065.721385323843, 5087.491250874883, 5088.516590923857, 5096.541311282002, 5143.768530416065, 4932.161532539549, 4932.358793356841, 4934.407871055496, 4934.629902757236, 4983.25251212321, 4733.006068725606, 4789.784459699392, 4856.174450343379, 4935.037667654773, 4990.700557966534, 4818.012307477029, 4831.73425461603, 4835.879103022312, 4898.0, 4903.60771138301, 4930.876728081683, 4942.184539550934, 4942.747424508072, 4944.826931057396, 5010.248462730619, 4670.743109129031, 4752.572268495852, 4821.927671085006, 4839.986655180783, 4842.104210721028], "value": 4589.690995268072, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.1834780394526, 571.4143003421492, 572.4847637126586, 575.8186171356023, 581.9883602327956, 27952.301192470197, 38468.80765596172, 39973.0, 40036.199276014464, 43876.780616096876, 39106.80446597763, 39570.0, 39572.604273957324, 39612.40581391282, 39638.80180599093, 7672.961635191817, 7763.456558040939, 7781.2996830285265, 7818.33544148748, 7823.139454659983, 7533.397328213757, 7617.428692848031, 7623.0, 7623.0, 7628.504147230412, 7634.465587408884, 7676.5394076355315, 7677.0403699537555, 7682.577458239794, 7683.423743219253, 7616.390688744915, 7649.6940122395035, 7650.38796896247, 7657.617119144021, 7660.961695191516, 7601.923980760177, 7655.119661238973, 7668.578228197447, 7669.233076692342, 7676.424268179909, 37638.247235055416, 37981.0, 38001.23997520034, 38002.42996355057, 38057.809710951544, 2942.0, 3150.6376766671897, 3531.7174626029855, 3574.6067932527403, 3575.106223444139, 27659.861700691366, 27759.444811103866, 28325.150245492667, 28423.289417764514, 29897.402051958838, 3291.687289707474, 3303.9834800826084, 3316.93366132676, 3362.2939182771647, 3454.4990976308545, 254556.27218639178, 255735.0, 255942.0, 256546.0, 258224.7088764563, 53221.46778532165, 53608.46391536113, 53617.731911340976, 53687.73156134273, 53715.462845371825, 32425.189370265933, 33425.49861752076, 33515.0, 35438.0, 51037.48962510328, 14597.78103328451, 14768.529395592028, 14776.926115369355, 14781.0, 14815.777763333559, 11118.388488633042, 11158.1631377647, 11185.105191584653, 11204.66386008421, 11219.214654974237, 17446.912765436344, 17464.563385915455, 17536.824631753774, 17543.385981490588, 17802.643947121243, 6732.2931092235585, 6832.419244364185, 6856.794296171123, 6877.862442751117, 6891.586504809728, 802.863513202757, 870.0646804684989, 873.8645509945968, 938.4932136646134, 947.4883562876107, 26935.0, 27116.1865144046, 27297.727022729916, 27351.0, 27379.315517111834, 899.6806133822399, 901.6979311930446, 928.1732854852597, 966.7341481092615, 1043.5199808088364, 14514.854851451562, 14534.0, 14709.0, 14745.705085898222, 14865.554033379016, 380860.0, 383758.0, 384951.0, 385126.0, 385300.0, 47270.5272947273, 47865.76067119712, 48078.278825817644, 48133.5186648129, 48269.275960860614, 1072.3619446429361, 1115.1803424482998, 1151.0963893343685, 1163.168334640736, 1206.481213078367, 24964.37589060288, 28496.85751571229, 28547.857260714394, 28854.278643034093, 29318.560221596268, 39759.0, 40194.0, 40194.19611607693, 40234.0, 40287.3956890641, 279218.0, 280333.0, 281465.0, 281495.0, 281793.0, 21905.671414929107, 22610.60840873882, 24124.105107387226, 24884.37789055288, 25043.624345635195, 38871.0, 39345.0, 39431.0, 39437.80281098576, 39870.40194397145, 34254.48618270778, 34319.485207722406, 34620.30759384848, 34761.47857782186, 35185.64814351926, 33835.492467612516, 33913.0, 33955.3208935815, 33987.0, 34778.652213477544, 34528.65471345355, 35206.29587408289, 35294.64705352914, 37375.0, 37877.81061094677, 30149.849250753607, 31479.68520314859, 32993.0, 33038.83480582582, 33108.50337244895, 24829.25512234636, 24893.128740493994, 25055.0, 25070.74929250684, 25353.239402817944], "value": 17005.45986804373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.5676059010957, 373.7682636765205, 378.081262532047, 385.40647402999383, 393.8444314495774, 15587.454439094634, 16807.91596042021, 17370.478885633434, 17481.41630965652, 17490.475285741417, 16595.253213605418, 17212.483625491222, 17398.30406783727, 17481.650366992653, 17489.475315740547, 4917.4344950330815, 5201.973990130044, 5203.427622961472, 5206.609504287175, 5208.244804503344, 3686.668199862012, 3792.677622402099, 3958.7624742515395, 4028.2346354192755, 5121.257417674435, 6059.757609695629, 6189.226346706661, 6196.504279657616, 6204.162438070848, 6205.472534834545, 5212.973935130319, 5471.917921231185, 5493.450654934514, 6200.968995155018, 6206.317305096436, 5403.189521571757, 5403.78384864605, 5417.187421886709, 5422.267993820863, 5422.647527910713, 11450.370229637367, 11673.941630291794, 11831.704207394798, 11862.169648124634, 12204.145709800317, 1772.7340898865273, 1828.7074068149166, 1926.5761532462836, 2130.286354071388, 2218.8557743746605, 8660.523671198082, 9138.862917056249, 9147.588358523815, 11674.299542027506, 12549.1215614907, 1640.3192675039845, 1697.4737831272346, 1699.6770613583503, 1747.6417334446407, 1754.324585034758, 70164.0, 72167.63916180439, 73833.26166738372, 74579.25420745778, 76968.23031769667, 10032.347897386793, 10133.240006999504, 10198.643047493302, 10374.792504150026, 10502.527386267637, 11197.49611267495, 14176.362063707158, 14195.219262940638, 14218.0, 14227.78658320126, 6457.967710161419, 6783.118194634666, 6828.385445309946, 7274.709011639581, 7299.5255308404785, 6331.778387756406, 6436.645984470899, 6488.870222595521, 6595.274519802818, 6867.862642747217, 3776.9433458498142, 3799.1831756172555, 3864.246471937945, 3900.1419687668667, 3912.0415498203124, 1776.8578513718871, 1832.7250912363252, 1861.8789778664343, 1867.570458794485, 1879.7556317678889, 769.2384539306158, 870.5125129927261, 903.2098153426695, 912.0378001208788, 955.4506158958627, 16176.75734863978, 16913.154342282836, 17128.886622369508, 17627.383041593483, 17848.643027139384, 808.1796976069294, 824.6824972385675, 827.9089300176976, 858.5878778186506, 897.4077109107957, 9399.0, 9429.245660347156, 9852.802943941224, 10032.749181270527, 10164.237682173867, 276054.0, 279510.0, 280003.0, 387889.0, 390664.0, 73238.0, 77798.61100694574, 79177.0, 80920.59539702382, 80952.0, 923.3767207135157, 933.8225737109927, 966.2366730283138, 974.3179774158118, 975.3075316525346, 15847.207639617742, 15936.760948585548, 16032.358705651643, 16669.583260418585, 17008.659826803643, 36720.26559468742, 37471.625283747904, 38051.80974095222, 38712.0, 38752.0, 48112.518874810805, 49674.0, 50969.0, 51412.0, 52531.73734131305, 7628.885566716614, 7764.145943946049, 7790.182030886675, 7908.802279942817, 7978.202179781975, 9117.863232051392, 11803.23278986863, 11833.230839995716, 11871.228370155912, 12619.0, 9944.701658950244, 10080.243981701255, 10627.840582391116, 10709.892901071202, 10883.292585981886, 9590.08894155054, 9938.503074846085, 9968.202543796624, 10229.948850255701, 10696.03735663794, 10062.295639305179, 10556.208284378856, 10584.523696433833, 10873.238873279111, 10916.345019298868, 6749.662516874235, 6923.757668481579, 7007.369336759716, 7390.0, 7570.242975702386, 8865.68970086044, 10705.250632455653, 10708.357498550113, 10977.451127443435, 11105.05607023412], "value": 8061.310694443074, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.4559593778731, 1349.838019437667, 1381.2058066611698, 1427.6644988427718, 1678.370611020868, 6569.704363303651, 7504.099508059034, 7767.262110099546, 7774.766756997285, 8019.198080191978, 7863.685452581904, 7923.366130709529, 7929.326007289373, 7932.524048557077, 7933.484323518967, 2178.313831143185, 2204.515006698524, 2205.3604454708197, 2208.182972300251, 2209.86740795552, 2162.3945295317258, 2202.3723238876923, 2233.419310979149, 2233.9888300558473, 2236.5303286309895, 2156.3746513511064, 2164.437246315961, 2165.372042107787, 2173.4892300309416, 2228.565429741201, 2141.2933731868447, 2155.698202251686, 2155.795199456049, 2155.8814265215565, 2158.7625361210253, 1961.5390383260064, 2010.6179825833044, 2015.3147929703825, 2016.4757163137542, 2024.2307922989316, 7660.502067365604, 7740.380769538423, 7753.108392534874, 7754.573498457583, 7873.173316801711, 4337.82648694055, 4478.731276123443, 4488.551144885518, 4518.977405112953, 4561.726296422192, 2725.8637068146513, 2829.6462942132234, 2865.398266364066, 2872.8707208175683, 2877.007432435806, 1006.4414250091196, 1009.4094954451655, 1027.2587963402036, 1029.7219750667277, 1044.7753732947376, 10828.837567436416, 10946.56213751449, 10948.178886583539, 10966.725831854188, 10977.28647637901, 4007.719459637797, 4097.713160078825, 4262.637675797592, 4271.615554600101, 4311.159323931866, 8521.786955326166, 8585.141485851489, 8606.225439710455, 8606.44058136219, 8610.225079742853, 4359.825606975749, 4375.190589740908, 4406.889827754332, 4411.09572537629, 4419.64642828573, 3495.720342372604, 3692.261547690419, 3776.1126135358168, 4087.9795601021806, 4092.7135100543273, 3295.0938491914917, 3439.5528581284275, 3446.6897979181495, 3453.3064209916306, 3475.5323720158467, 1041.5417216424748, 1041.5885725138528, 1052.400131924811, 1058.9947050264852, 1061.3101484035285, 637.9266384365765, 699.2657709405104, 724.0225695311393, 751.2262369759156, 759.4456047085658, 4761.40482439695, 4974.32846565709, 5056.924146137811, 5096.9490305097215, 5161.974190129025, 407.6046235151898, 409.58018031517935, 413.0808950086035, 430.34587427110614, 435.9934600980988, 3251.4472539668313, 3289.786163899339, 3313.2710803623163, 3323.4350160472777, 3349.7487688423466, 9215.078492150866, 9491.288153388385, 9571.138597526256, 9578.281628877861, 9604.567794449267, 9498.95250523743, 9503.57233924475, 9510.09654082861, 9529.714108576754, 9539.666111686196, 7084.716611335489, 7095.361417472349, 7135.429165666734, 7144.535605185646, 7144.714211431485, 4814.229723244264, 4874.805007799649, 4888.877778055577, 4947.826826061215, 5055.317532133262, 8501.914980850112, 8600.827983440422, 8683.565821708762, 8732.170443808081, 8829.293656507336, 8037.598120094089, 8804.295656347349, 8887.866681999645, 8889.19997200255, 8915.509646969478, 5738.913916291175, 6160.168377269011, 6299.590526615755, 6324.494040476843, 6503.154589903283, 7459.813504662427, 7470.551766893786, 7475.514091584029, 7550.811229719081, 7553.093628764584, 6757.45940324763, 6857.142857142857, 6904.27505111956, 6928.826779330557, 6977.1278590176225, 6327.905081423874, 6456.160699109274, 6457.257415397289, 6484.610923344614, 6488.708008139551, 5702.657840529581, 5820.214271073351, 5886.087656413263, 5888.676122813286, 6014.8195554133445, 4263.126059157803, 4346.891327716706, 4413.117376524772, 4448.0, 4577.610903073276, 4511.639068874548, 4532.954670453385, 4548.6361091113295, 4614.884627884329, 4626.467956184947], "value": 3937.831170266159, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.06651579650475, 304.844529290062, 309.1529209964697, 311.88460269700215, 312.048252828872, 4744.976275118624, 4817.421909370872, 4817.711586996179, 4822.0, 4850.4179498460235, 4710.764461776915, 4729.810807567693, 4779.139754844126, 4794.784234709437, 4800.255960326146, 3521.6830485256323, 3638.435472746718, 3653.6163702811223, 3706.5737440194316, 3713.6657700806923, 3584.13980644646, 3716.8513259469587, 3725.739198256123, 3729.552453705559, 3747.4004159334513, 3679.7056235501095, 3681.6686498215154, 3716.981415092934, 3720.6465385788306, 3730.451862477082, 3620.7827530348263, 3707.4068149096015, 3724.8510059597584, 3724.8639165054647, 3729.1609387887506, 3281.196106953788, 3283.1928700960575, 3288.7338374725905, 3306.206510437502, 3306.35526072416, 4569.977150114228, 4575.816967321303, 4587.816487340502, 4591.403117594725, 4601.5168407317415, 4234.9364759528635, 4240.787960601958, 4261.423273388835, 4277.593628605277, 4282.785860706983, 4744.762761861927, 4882.048000639877, 4895.241237608177, 4920.458749537548, 4929.338157395003, 3597.874074407383, 3604.981975090108, 3605.206854492034, 3605.441156620728, 3605.94591081134, 5154.252633368141, 5161.0, 5185.196294574347, 5205.167173252299, 5208.479152084799, 3442.6901578858024, 3633.981830090833, 3713.1830997180577, 3719.442083687414, 3721.846227669377, 3669.4679271506016, 3680.1351682354625, 3688.8340024698955, 3693.741438099361, 3694.261147770457, 4202.411662367271, 4215.388768628532, 4217.1144059747185, 4217.283061879488, 4228.809703563347, 5018.272350509155, 5027.648064635514, 5028.34631497903, 5049.116404629175, 5053.267276244924, 1211.794264706605, 1266.9809952850715, 1269.9746005079846, 1270.9110362274553, 1280.8591054983945, 794.3605397654943, 813.3980854167874, 828.5981299069946, 835.1690068381998, 836.93304535637, 3903.726739128234, 4109.958900411018, 4212.410262563244, 4248.4477017987465, 4318.805653745589, 5068.797248110108, 5091.66904151229, 5097.796088156507, 5109.923351149736, 5121.513456221653, 4292.763897985578, 4305.461817272841, 4308.9353659695125, 4316.935245971313, 4318.222719910384, 2592.325995241251, 2661.9068332608654, 2673.5187666220268, 2686.597010448448, 2696.258528904567, 4780.25905984566, 4793.257045158006, 4797.0, 4814.398200224972, 4819.795051237191, 5185.211102169746, 5194.168932970707, 5199.844004679866, 5214.556762675139, 5220.895582088337, 4622.976885115553, 4817.301491283814, 4879.658423910292, 4907.77914993819, 4922.5569698728, 5126.589872810092, 5155.458676838878, 5159.509846564571, 5164.870878228075, 5173.4826517347965, 5058.64589478733, 5080.26336181259, 5083.0, 5115.846524604268, 5117.641765076558, 4709.1398897436, 4821.807127714852, 4846.39420072491, 4861.854144375674, 4879.268109783419, 3615.837287322024, 3651.3610118229735, 3671.0, 3675.4746780085966, 3731.272401881607, 5219.0, 5227.320448341691, 5227.529522343008, 5230.450802665665, 5257.8159764408065, 4806.519348065165, 5002.299678045004, 5009.223570346602, 5014.674046187132, 5057.811414317706, 4999.925001124913, 5001.274815151855, 5001.924971125509, 5005.674631148963, 5019.472955339636, 4957.8844759928725, 4975.950240497548, 4981.687178026572, 4984.37695288089, 5002.549770520671, 4816.879578010437, 4906.239532872411, 5028.421731500925, 5044.267242240789, 5053.8881446081805, 4776.307435421914, 4845.9757701211265, 4914.238293064581, 4916.164252077227, 4929.433115191799], "value": 3755.363565601802, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.8542783283929, 611.2786911444493, 618.9195404597401, 692.2973182220046, 723.3778950102908, 12542.247465152086, 13009.73980520389, 13044.543440979567, 13048.608541743763, 13052.28212448315, 13304.93347533261, 13398.062135650509, 13406.865931340684, 13418.396172172252, 13460.932695336534, 619.3868070610087, 633.0883527720091, 740.6778051547567, 745.7129005332931, 767.7965339185121, 777.5218240781907, 779.9181085985975, 780.9062912450514, 782.5774081995744, 783.2089589514603, 815.8980127484065, 819.6557445872747, 819.7458787775779, 819.815541503163, 820.1798201798201, 639.83684160539, 651.5439192565224, 662.4865729059969, 664.6344510519195, 816.7753867686404, 486.48918635432653, 578.1674388879999, 590.4833270887973, 590.7873165660359, 598.0341748076868, 12061.577844775391, 12073.396330183456, 12083.758324833541, 12402.503899844085, 12453.315067671274, 12386.442610082568, 12599.93700031503, 12976.41606127717, 13149.802752958618, 13587.456501740018, 11427.257228280136, 11597.130215233807, 11748.882511174865, 11931.224470409483, 12430.689232769162, 3989.6608788253034, 3997.4603428537066, 4030.234255491447, 4103.671706263492, 4136.834526618932, 63256.68371658158, 63504.0, 64634.67682661556, 64844.351556484304, 65455.67272163656, 9270.536473176315, 9428.57142857143, 9483.336166468282, 9558.091981261769, 9784.266180036526, 15931.522054338388, 15959.601009974844, 15964.600884977968, 16069.678606427806, 16088.356465741239, 6760.0, 6778.356056174655, 6786.219584747719, 6787.966060169766, 6794.11676482064, 3793.1804091754493, 3801.8099095045136, 3830.942535861964, 3904.902377440587, 3913.8238779255003, 2739.4247208086126, 2829.462402143586, 2844.815087019337, 2849.244950088228, 2851.1731597836397, 473.8483685220696, 476.0312763526277, 478.258699016523, 486.23660852461296, 489.1610887328249, 457.80543269110495, 458.20730136863415, 458.5712358944331, 459.99080018400116, 462.8310666606664, 6972.058772065808, 7021.473389495808, 7485.465479576674, 7487.962560187273, 8090.197607124642, 370.762711864407, 405.65519308588057, 414.57506056291936, 441.3446032641589, 447.2396925227127, 3430.8627654893526, 3466.9479957800654, 3571.7499775016017, 3577.123604716809, 3631.5642122945414, 98913.0, 102343.0, 103107.0, 104570.0, 107212.0, 90284.54857725669, 93146.53426732958, 93660.0, 94683.52658236664, 96478.0352196469, 78513.0, 78895.0, 80244.19755802368, 80428.59785701035, 80815.0, 7067.2579379164445, 7223.024891639561, 7288.526245794006, 7410.925890741024, 7460.850782984418, 45547.31679024751, 45934.77032614816, 47665.0, 48058.51941480541, 48572.27141592808, 62749.372506274354, 68166.0, 68465.65767171331, 68802.65598671975, 69784.0, 9289.256859451363, 9302.488363140092, 9350.81298374015, 9533.380330278509, 9535.092981403886, 9689.30437173506, 10020.398776073458, 10106.595736170471, 10183.18534517222, 10276.743081423025, 9596.328257021942, 9677.274204434554, 9715.36850104741, 10171.440570768384, 10193.133583645176, 8706.651733930572, 9013.0, 9281.582328795355, 9420.434773913586, 9484.33609647318, 9109.362344635812, 9514.762130946781, 9635.807283854423, 9772.804543909224, 9831.557579908778, 8310.168983101641, 8431.072582015851, 8454.154584541497, 8709.085546017577, 8787.648494060166, 7452.739154129579, 7813.531188128503, 8294.58527073656, 8655.133148210918, 8928.464292142493], "value": 6163.291166320778, "unit": "score"}], "name": "dromaeo_css", "value": 6210.001106243975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 115.0, 113.0, 116.0, 115.0, 114.0, 115.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 124.0, 128.0, 160.0, 131.0, 123.0, 126.0, 125.0, 153.0, 123.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 109.0, 79.0, 79.0, 76.0, 78.0, 109.0, 77.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 113.0, 84.0, 83.0, 84.0, 103.0, 82.0, 84.0, 113.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 147.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 62.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 83.0, 83.0, 87.0, 83.0, 82.0, 82.0, 82.0, 88.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 131.0, 130.0, 130.0, 131.0, 132.0, 133.0, 181.0, 144.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 176.0, 181.0, 173.0, 176.0, 178.0, 178.0, 179.0, 178.0, 178.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 76.0, 68.0, 68.0, 70.0, 69.0, 69.0, 68.0, 68.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1544.5000000000002, "alertThreshold": 2.0}]} 05:26:45 INFO - Return code: 0 05:26:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:45 INFO - # TBPL SUCCESS # 05:26:45 INFO - Running post-action listener: _resource_record_post_action 05:26:45 INFO - [mozharness: 2016-09-18 12:26:45.775960Z] Finished run-tests step (success) 05:26:45 INFO - Running post-run listener: _resource_record_post_run 05:26:45 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391602176; Read time: 12; Write time: 286232 05:26:45 INFO - TinderboxPrint: CPU usage
13.0% 05:26:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:26:45 INFO - TinderboxPrint: I/O write bytes / time
391,602,176 / 286,232 05:26:45 INFO - TinderboxPrint: CPU idle
8,345.5 (86.9%) 05:26:45 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 05:26:45 INFO - TinderboxPrint: Swap in / out
0 / 0 05:26:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99811328; Read time: 0; Write time: 85572 05:26:45 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291790848; Read time: 0; Write time: 200660 05:26:46 INFO - Running post-run listener: _upload_blobber_files 05:26:46 INFO - Blob upload gear active. 05:26:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:46 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 05:26:46 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:26:46 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:26:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:49 INFO - (blobuploader) - INFO - Done attempting. 05:26:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:51 INFO - (blobuploader) - INFO - Done attempting. 05:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:51 INFO - (blobuploader) - INFO - Done attempting. 05:26:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:51 INFO - Return code: 0 05:26:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/128169fbaa9c6b1eefcdba2c09a3005805a1450e1b4b332c080427672e69d297acf3ba09cf9a7ab5ba30937c91ec58834aebf4ec05c93aec3f033e7ac6badae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33700bd487eea4f5ae1a586c784b89772c1b57a2c5f69d49792cc5be1e089cd4513f5615649f2d8144dc094fb1d952f9d2aa1debf91d7223785e024c81a7c671", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3b69cecca08b523e7ea587c3483297469d59bcb6aeab461692e6c98b96c2117dafce05b24f2c40d1e50f6a7e3477d7c7467f72d33155f35ad08d097ff98e6cc"} 05:26:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:51 INFO - Contents: 05:26:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/128169fbaa9c6b1eefcdba2c09a3005805a1450e1b4b332c080427672e69d297acf3ba09cf9a7ab5ba30937c91ec58834aebf4ec05c93aec3f033e7ac6badae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33700bd487eea4f5ae1a586c784b89772c1b57a2c5f69d49792cc5be1e089cd4513f5615649f2d8144dc094fb1d952f9d2aa1debf91d7223785e024c81a7c671", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3b69cecca08b523e7ea587c3483297469d59bcb6aeab461692e6c98b96c2117dafce05b24f2c40d1e50f6a7e3477d7c7467f72d33155f35ad08d097ff98e6cc"} 05:26:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:51 INFO - Copying logs to upload dir... 05:26:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.187033 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-18 05:26:51.911570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:51.919026) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/128169fbaa9c6b1eefcdba2c09a3005805a1450e1b4b332c080427672e69d297acf3ba09cf9a7ab5ba30937c91ec58834aebf4ec05c93aec3f033e7ac6badae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33700bd487eea4f5ae1a586c784b89772c1b57a2c5f69d49792cc5be1e089cd4513f5615649f2d8144dc094fb1d952f9d2aa1debf91d7223785e024c81a7c671", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3b69cecca08b523e7ea587c3483297469d59bcb6aeab461692e6c98b96c2117dafce05b24f2c40d1e50f6a7e3477d7c7467f72d33155f35ad08d097ff98e6cc"} build_url:https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/128169fbaa9c6b1eefcdba2c09a3005805a1450e1b4b332c080427672e69d297acf3ba09cf9a7ab5ba30937c91ec58834aebf4ec05c93aec3f033e7ac6badae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33700bd487eea4f5ae1a586c784b89772c1b57a2c5f69d49792cc5be1e089cd4513f5615649f2d8144dc094fb1d952f9d2aa1debf91d7223785e024c81a7c671", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3b69cecca08b523e7ea587c3483297469d59bcb6aeab461692e6c98b96c2117dafce05b24f2c40d1e50f6a7e3477d7c7467f72d33155f35ad08d097ff98e6cc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:51.943587) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:51.946959) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474200364.848400-792562695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:52.027394) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 05:26:52.030730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 05:26:52.031071) ========= ========= Total master_lag: 0.18 =========