builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1474200363.22 results: success (0) buildid: 20160918000553 builduid: e66f368b4db4489a8f57c64eacec42df revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.221643) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.222197) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.222519) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.234496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.234811) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 05:06:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2016-09-18 05:06:03 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171323 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.418579) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.418938) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:03.469660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 05:06:03.470124) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 05:06:03,950 truncating revision to first 12 chars 2016-09-18 05:06:03,950 Setting DEBUG logging. 2016-09-18 05:06:03,950 attempt 1/10 2016-09-18 05:06:03,950 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:04,664 unpacking tar archive at: fx-team-fd81de7deaab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.058260 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 05:06:04.542437) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:04.542833) ========= script_repo_revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:04.543400) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:04.543726) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 05:06:04.553233) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-18 05:06:04.553604) ========= /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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:06:05 INFO - MultiFileLogger online at 20160918 05:06:05 in /builds/slave/test 05:06:05 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:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:05 INFO - {'append_to_log': False, 05:06:05 INFO - 'base_work_dir': '/builds/slave/test', 05:06:05 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:06:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:05 INFO - 'branch': 'Fx-Team-Non-PGO', 05:06:05 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:05 INFO - 'config_files': ('talos/linux_config.py',), 05:06:05 INFO - 'default_actions': ('clobber', 05:06:05 INFO - 'read-buildbot-config', 05:06:05 INFO - 'download-and-extract', 05:06:05 INFO - 'populate-webroot', 05:06:05 INFO - 'create-virtualenv', 05:06:05 INFO - 'install', 05:06:05 INFO - 'run-tests'), 05:06:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:05 INFO - 'download_minidump_stackwalk': True, 05:06:05 INFO - 'download_symbols': 'ondemand', 05:06:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:05 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:05 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:05 INFO - 'installer_path': 'installer.exe', 05:06:05 INFO - 'log_level': 'info', 05:06:05 INFO - 'log_name': 'talos', 05:06:05 INFO - 'log_to_console': True, 05:06:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:06:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:06:05 INFO - 'opt_config_files': (), 05:06:05 INFO - 'pip_index': False, 05:06:05 INFO - 'sps_profile': False, 05:06:05 INFO - 'sps_profile_interval': 0, 05:06:05 INFO - 'suite': 'dromaeojs', 05:06:05 INFO - 'system_bits': '32', 05:06:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:05 INFO - 'title': 'talos-linux64-ix-030', 05:06:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:05 INFO - 'use_talos_json': True, 05:06:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:05 INFO - 'work_dir': 'build'} 05:06:05 INFO - [mozharness: 2016-09-18 12:06:05.130961Z] Running clobber step. 05:06:05 INFO - Running pre-action listener: _resource_record_pre_action 05:06:05 INFO - Running main action method: clobber 05:06:05 INFO - rmtree: /builds/slave/test/build 05:06:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:05 INFO - Running post-action listener: _resource_record_post_action 05:06:05 INFO - [mozharness: 2016-09-18 12:06:05.590179Z] Finished clobber step (success) 05:06:05 INFO - [mozharness: 2016-09-18 12:06:05.590281Z] Running read-buildbot-config step. 05:06:05 INFO - Running pre-action listener: _resource_record_pre_action 05:06:05 INFO - Running main action method: read_buildbot_config 05:06:05 INFO - Using buildbot properties: 05:06:05 INFO - { 05:06:05 INFO - "project": "", 05:06:05 INFO - "product": "firefox", 05:06:05 INFO - "script_repo_revision": "production", 05:06:05 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:06:05 INFO - "repository": "", 05:06:05 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:06:05 INFO - "buildid": "20160918000553", 05:06:05 INFO - "pgo_build": "False", 05:06:05 INFO - "basedir": "/builds/slave/test", 05:06:05 INFO - "buildnumber": 2167, 05:06:05 INFO - "slavename": "talos-linux64-ix-030", 05:06:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:06:05 INFO - "platform": "ubuntu64_hw", 05:06:05 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:06:05 INFO - "branch": "fx-team", 05:06:05 INFO - "repo_path": "integration/fx-team", 05:06:05 INFO - "revision": "fd81de7deaaba19cb7a899b08da0472e32bd215b", 05:06:05 INFO - "stage_platform": "linux64", 05:06:05 INFO - "builduid": "e66f368b4db4489a8f57c64eacec42df", 05:06:05 INFO - "slavebuilddir": "test" 05:06:05 INFO - } 05:06:05 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:05 INFO - Running post-action listener: _resource_record_post_action 05:06:05 INFO - [mozharness: 2016-09-18 12:06:05.592735Z] Finished read-buildbot-config step (success) 05:06:05 INFO - [mozharness: 2016-09-18 12:06:05.592825Z] Running download-and-extract step. 05:06:05 INFO - Running pre-action listener: _resource_record_pre_action 05:06:05 INFO - Running main action method: download_and_extract 05:06:05 INFO - mkdir: /builds/slave/test/build/tests 05:06:05 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:05 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:05 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:05 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:06 INFO - Downloaded 1361 bytes. 05:06:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:06:06 INFO - Using the following test package requirements: 05:06:06 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:06:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:06:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'jsshell-linux-x86_64.zip'], 05:06:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:06:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:06:06 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:06:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:06:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:06:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:06:06 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:06 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:06 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:06 INFO - Expected file size: 20365610 05:06:10 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:10 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:10 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:10 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:11 INFO - Expected file size: 20365610 05:06:16 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:16 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:16 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:16 INFO - Expected file size: 14414059 05:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:17 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:17 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:17 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:18 INFO - Downloaded 55602959 bytes. 05:06:18 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:18 INFO - mkdir: /builds/slave/test/properties 05:06:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:18 INFO - Contents: 05:06:18 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:18 INFO - Running post-action listener: _resource_record_post_action 05:06:18 INFO - Running post-action listener: set_extra_try_arguments 05:06:18 INFO - [mozharness: 2016-09-18 12:06:18.924263Z] Finished download-and-extract step (success) 05:06:18 INFO - [mozharness: 2016-09-18 12:06:18.924493Z] Running populate-webroot step. 05:06:18 INFO - Running pre-action listener: _resource_record_pre_action 05:06:18 INFO - Running main action method: populate_webroot 05:06:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:18 INFO - '--tppagecycles', 05:06:18 INFO - '7'], 05:06:18 INFO - 'tests': ['tsvgm']}}, 05:06:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['damp', 'tps']}, 05:06:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'tests': ['damp', 'tps']}, 05:06:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['dromaeo_dom']}, 05:06:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['basic_compositor_video']}, 05:06:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:06:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['a11yr', 05:06:18 INFO - 'ts_paint', 05:06:18 INFO - 'tpaint', 05:06:18 INFO - 'sessionrestore', 05:06:18 INFO - 'sessionrestore_no_auto_restore', 05:06:18 INFO - 'tabpaint']}, 05:06:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['tsvgx', 05:06:18 INFO - 'tsvgr_opacity', 05:06:18 INFO - 'tart', 05:06:18 INFO - 'tscrollx', 05:06:18 INFO - 'cart']}, 05:06:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:18 INFO - 'tsvgr_opacity', 05:06:18 INFO - 'tart', 05:06:18 INFO - 'tscrollx', 05:06:18 INFO - 'cart']}, 05:06:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'talos_options': ['--disable-e10s'], 05:06:18 INFO - 'tests': ['tp5o']}, 05:06:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'tests': ['tp5o']}, 05:06:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'talos_options': ['--disable-e10s', 05:06:18 INFO - '--xperf_path', 05:06:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:18 INFO - 'tests': ['tp5n']}, 05:06:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:18 INFO - 'talos_options': ['--xperf_path', 05:06:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:18 INFO - 'tests': ['tp5n']}}, 05:06:18 INFO - 'talos.zip': {'path': '', 05:06:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:18 INFO - Running post-action listener: _resource_record_post_action 05:06:18 INFO - [mozharness: 2016-09-18 12:06:18.941148Z] Finished populate-webroot step (success) 05:06:18 INFO - [mozharness: 2016-09-18 12:06:18.941237Z] Running create-virtualenv step. 05:06:18 INFO - Running pre-action listener: _resource_record_pre_action 05:06:18 INFO - Running main action method: create_virtualenv 05:06:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:18 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:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:19 INFO - Using real prefix '/usr' 05:06:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:21 INFO - Installing pip.................done. 05:06:21 INFO - Return code: 0 05:06:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 05:06:21 INFO - 'HOME': '/home/cltbld', 05:06:21 INFO - 'LANG': 'en_US.UTF-8', 05:06:21 INFO - 'LANGUAGE': 'en_US:en', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MAIL': '/var/mail/cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SHLVL': '1', 05:06:21 INFO - 'TERM': 'linux', 05:06:21 INFO - 'TMOUT': '86400', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - '_': '/tools/buildbot/bin/python'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 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:21 INFO - Cleaning up... 05:06:21 INFO - Return code: 0 05:06:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 05:06:21 INFO - 'HOME': '/home/cltbld', 05:06:21 INFO - 'LANG': 'en_US.UTF-8', 05:06:21 INFO - 'LANGUAGE': 'en_US:en', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MAIL': '/var/mail/cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SHLVL': '1', 05:06:21 INFO - 'TERM': 'linux', 05:06:21 INFO - 'TMOUT': '86400', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - '_': '/tools/buildbot/bin/python'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 INFO - Downloading/unpacking psutil>=3.1.1 05:06:21 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:21 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:21 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:21 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:21 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:21 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:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:24 INFO - Installing collected packages: psutil 05:06:24 INFO - Running setup.py install for psutil 05:06:24 INFO - building 'psutil._psutil_linux' extension 05:06:24 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:24 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:24 INFO - building 'psutil._psutil_posix' extension 05:06:24 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:24 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:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:24 INFO - Successfully installed psutil 05:06:24 INFO - Cleaning up... 05:06:24 INFO - Return code: 0 05:06:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:24 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 05:06:24 INFO - 'HOME': '/home/cltbld', 05:06:24 INFO - 'LANG': 'en_US.UTF-8', 05:06:24 INFO - 'LANGUAGE': 'en_US:en', 05:06:24 INFO - 'LOGNAME': 'cltbld', 05:06:24 INFO - 'MAIL': '/var/mail/cltbld', 05:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:24 INFO - 'MOZ_NO_REMOTE': '1', 05:06:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:24 INFO - 'NO_EM_RESTART': '1', 05:06:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:24 INFO - 'PWD': '/builds/slave/test', 05:06:24 INFO - 'SHELL': '/bin/bash', 05:06:24 INFO - 'SHLVL': '1', 05:06:24 INFO - 'TERM': 'linux', 05:06:24 INFO - 'TMOUT': '86400', 05:06:24 INFO - 'USER': 'cltbld', 05:06:24 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:24 INFO - '_': '/tools/buildbot/bin/python'} 05:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:24 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:24 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:24 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:24 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:24 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:24 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 - Downloading mozsystemmonitor-0.3.tar.gz 05:06:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:26 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:26 INFO - Installing collected packages: mozsystemmonitor 05:06:26 INFO - Running setup.py install for mozsystemmonitor 05:06:26 INFO - Successfully installed mozsystemmonitor 05:06:26 INFO - Cleaning up... 05:06:26 INFO - Return code: 0 05:06:26 INFO - Installing jsonschema==2.5.1 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', '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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:26 INFO - '_': '/tools/buildbot/bin/python'} 05:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:27 INFO - Downloading/unpacking jsonschema==2.5.1 05:06:27 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:27 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:27 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:27 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:27 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:27 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:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:29 INFO - warning: no files found matching '*.txt' 05:06:29 INFO - no previously-included directories found matching 'build' 05:06:29 INFO - no previously-included directories found matching 'dist' 05:06:29 INFO - no previously-included directories found matching '.git*' 05:06:29 INFO - Installing collected packages: jsonschema, functools32 05:06:29 INFO - Running setup.py install for functools32 05:06:29 INFO - warning: no files found matching '*.txt' 05:06:29 INFO - no previously-included directories found matching 'build' 05:06:29 INFO - no previously-included directories found matching 'dist' 05:06:29 INFO - no previously-included directories found matching '.git*' 05:06:29 INFO - Successfully installed jsonschema functools32 05:06:29 INFO - Cleaning up... 05:06:29 INFO - Return code: 0 05:06:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06:29 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:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:29 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:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:29 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:29 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:29 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:29 INFO - Using env: {'DISPLAY': ':0', 05:06:29 INFO - 'HOME': '/home/cltbld', 05:06:29 INFO - 'LANG': 'en_US.UTF-8', 05:06:29 INFO - 'LANGUAGE': 'en_US:en', 05:06:29 INFO - 'LOGNAME': 'cltbld', 05:06:29 INFO - 'MAIL': '/var/mail/cltbld', 05:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:29 INFO - 'MOZ_NO_REMOTE': '1', 05:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:29 INFO - 'NO_EM_RESTART': '1', 05:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:29 INFO - 'PWD': '/builds/slave/test', 05:06:29 INFO - 'SHELL': '/bin/bash', 05:06:29 INFO - 'SHLVL': '1', 05:06:29 INFO - 'TERM': 'linux', 05:06:29 INFO - 'TMOUT': '86400', 05:06:29 INFO - 'USER': 'cltbld', 05:06:29 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:29 INFO - '_': '/tools/buildbot/bin/python'} 05:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:29 INFO - Cleaning up... 05:06:29 INFO - Return code: 0 05:06:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:29 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:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:29 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:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:29 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:29 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:29 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:29 INFO - Using env: {'DISPLAY': ':0', 05:06:29 INFO - 'HOME': '/home/cltbld', 05:06:29 INFO - 'LANG': 'en_US.UTF-8', 05:06:29 INFO - 'LANGUAGE': 'en_US:en', 05:06:29 INFO - 'LOGNAME': 'cltbld', 05:06:29 INFO - 'MAIL': '/var/mail/cltbld', 05:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:29 INFO - 'MOZ_NO_REMOTE': '1', 05:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:29 INFO - 'NO_EM_RESTART': '1', 05:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:29 INFO - 'PWD': '/builds/slave/test', 05:06:29 INFO - 'SHELL': '/bin/bash', 05:06:29 INFO - 'SHLVL': '1', 05:06:29 INFO - 'TERM': 'linux', 05:06:29 INFO - 'TMOUT': '86400', 05:06:29 INFO - 'USER': 'cltbld', 05:06:29 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:29 INFO - '_': '/tools/buildbot/bin/python'} 05:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:29 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:29 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:29 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:29 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:29 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:29 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 blobuploader-1.2.4.tar.gz 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:31 INFO - Downloading/unpacking requests==1.2.3. (from 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:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - Downloading docopt-0.6.1.tar.gz 05:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:32 INFO - Running setup.py install for blobuploader 05:06:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:32 INFO - Running setup.py install for requests 05:06:33 INFO - Running setup.py install for docopt 05:06:33 INFO - Successfully installed blobuploader requests docopt 05:06:33 INFO - Cleaning up... 05:06:33 INFO - Return code: 0 05:06:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:33 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:33 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:33 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:33 INFO - Using env: {'DISPLAY': ':0', 05:06:33 INFO - 'HOME': '/home/cltbld', 05:06:33 INFO - 'LANG': 'en_US.UTF-8', 05:06:33 INFO - 'LANGUAGE': 'en_US:en', 05:06:33 INFO - 'LOGNAME': 'cltbld', 05:06:33 INFO - 'MAIL': '/var/mail/cltbld', 05:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:33 INFO - 'MOZ_NO_REMOTE': '1', 05:06:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:33 INFO - 'NO_EM_RESTART': '1', 05:06:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:33 INFO - 'PWD': '/builds/slave/test', 05:06:33 INFO - 'SHELL': '/bin/bash', 05:06:33 INFO - 'SHLVL': '1', 05:06:33 INFO - 'TERM': 'linux', 05:06:33 INFO - 'TMOUT': '86400', 05:06:33 INFO - 'USER': 'cltbld', 05:06:33 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:33 INFO - '_': '/tools/buildbot/bin/python'} 05:06:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:33 INFO - Running setup.py (path:/tmp/pip-gjHyXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:33 INFO - Running setup.py (path:/tmp/pip-YUNQuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:33 INFO - Running setup.py (path:/tmp/pip-I_PKar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:33 INFO - Running setup.py (path:/tmp/pip-lfrGCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:33 INFO - Running setup.py (path:/tmp/pip-8Oy1UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:33 INFO - Running setup.py (path:/tmp/pip-AzmGf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:33 INFO - Running setup.py (path:/tmp/pip-UmLwj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:33 INFO - Running setup.py (path:/tmp/pip-sHYC1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:34 INFO - Running setup.py (path:/tmp/pip-sAa4wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:34 INFO - Running setup.py (path:/tmp/pip-Mvvyt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:34 INFO - Running setup.py (path:/tmp/pip-E0PR0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:34 INFO - Running setup.py (path:/tmp/pip-CdvHYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:34 INFO - Running setup.py (path:/tmp/pip-ZDCr_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:34 INFO - Running setup.py (path:/tmp/pip-nSMRta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:34 INFO - Running setup.py (path:/tmp/pip-0naH19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:34 INFO - Running setup.py (path:/tmp/pip-WLLMoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:34 INFO - Running setup.py (path:/tmp/pip-bfjT7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:34 INFO - Running setup.py install for manifestparser 05:06:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:34 INFO - Running setup.py install for mozcrash 05:06:34 INFO - Running setup.py install for mozdebug 05:06:34 INFO - Running setup.py install for mozdevice 05:06:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozfile 05:06:35 INFO - Running setup.py install for mozhttpd 05:06:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozinfo 05:06:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozInstall 05:06:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozleak 05:06:35 INFO - Running setup.py install for mozlog 05:06:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for moznetwork 05:06:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozprocess 05:06:35 INFO - Running setup.py install for mozprofile 05:06:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozrunner 05:06:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozscreenshot 05:06:36 INFO - Running setup.py install for moztest 05:06:36 INFO - Running setup.py install for mozversion 05:06:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:36 INFO - Cleaning up... 05:06:36 INFO - Return code: 0 05:06:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:36 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:36 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:36 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:36 INFO - Using env: {'DISPLAY': ':0', 05:06:36 INFO - 'HOME': '/home/cltbld', 05:06:36 INFO - 'LANG': 'en_US.UTF-8', 05:06:36 INFO - 'LANGUAGE': 'en_US:en', 05:06:36 INFO - 'LOGNAME': 'cltbld', 05:06:36 INFO - 'MAIL': '/var/mail/cltbld', 05:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:36 INFO - 'MOZ_NO_REMOTE': '1', 05:06:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:36 INFO - 'NO_EM_RESTART': '1', 05:06:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:36 INFO - 'PWD': '/builds/slave/test', 05:06:36 INFO - 'SHELL': '/bin/bash', 05:06:36 INFO - 'SHLVL': '1', 05:06:36 INFO - 'TERM': 'linux', 05:06:36 INFO - 'TMOUT': '86400', 05:06:36 INFO - 'USER': 'cltbld', 05:06:36 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:36 INFO - '_': '/tools/buildbot/bin/python'} 05:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:36 INFO - Running setup.py (path:/tmp/pip-Xxd7fH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:36 INFO - Running setup.py (path:/tmp/pip-satu6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:36 INFO - Running setup.py (path:/tmp/pip-NJAssq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:36 INFO - Running setup.py (path:/tmp/pip-amiPqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:37 INFO - Running setup.py (path:/tmp/pip-dItsVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:37 INFO - Running setup.py (path:/tmp/pip-XHIOB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:37 INFO - Running setup.py (path:/tmp/pip-hajGyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:37 INFO - Running setup.py (path:/tmp/pip-Hby4ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:37 INFO - Running setup.py (path:/tmp/pip-GfyLff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:37 INFO - Running setup.py (path:/tmp/pip-XtSpWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:37 INFO - Running setup.py (path:/tmp/pip-IzOnyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:37 INFO - Running setup.py (path:/tmp/pip-sN8GtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:37 INFO - Running setup.py (path:/tmp/pip-_TcPWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:37 INFO - Running setup.py (path:/tmp/pip-ViEz6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:37 INFO - Running setup.py (path:/tmp/pip-iI1hW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:37 INFO - Running setup.py (path:/tmp/pip-uaG7Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:37 INFO - Running setup.py (path:/tmp/pip-Q6xHtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:37 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:37 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:37 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:37 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:37 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:37 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:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:37 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:37 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:37 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:37 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:37 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:37 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 - Downloading blessings-1.6.tar.gz 05:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:39 INFO - Installing collected packages: blessings 05:06:39 INFO - Running setup.py install for blessings 05:06:40 INFO - Successfully installed blessings 05:06:40 INFO - Cleaning up... 05:06:40 INFO - Return code: 0 05:06:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:40 INFO - Reading from file tmpfile_stdout 05:06:40 INFO - Current package versions: 05:06:40 INFO - argparse == 1.2.1 05:06:40 INFO - blessings == 1.6 05:06:40 INFO - blobuploader == 1.2.4 05:06:40 INFO - docopt == 0.6.1 05:06:40 INFO - functools32 == 3.2.3-2 05:06:40 INFO - jsonschema == 2.5.1 05:06:40 INFO - manifestparser == 1.1 05:06:40 INFO - mozInstall == 1.12 05:06:40 INFO - mozcrash == 0.17 05:06:40 INFO - mozdebug == 0.1 05:06:40 INFO - mozdevice == 0.48 05:06:40 INFO - mozfile == 1.2 05:06:40 INFO - mozhttpd == 0.7 05:06:40 INFO - mozinfo == 0.9 05:06:40 INFO - mozleak == 0.1 05:06:40 INFO - mozlog == 3.4 05:06:40 INFO - moznetwork == 0.27 05:06:40 INFO - mozprocess == 0.23 05:06:40 INFO - mozprofile == 0.28 05:06:40 INFO - mozrunner == 6.12 05:06:40 INFO - mozscreenshot == 0.1 05:06:40 INFO - mozsystemmonitor == 0.3 05:06:40 INFO - moztest == 0.7 05:06:40 INFO - mozversion == 1.4 05:06:40 INFO - psutil == 3.1.1 05:06:40 INFO - requests == 1.2.3 05:06:40 INFO - wsgiref == 0.1.2 05:06:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:40 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:40 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:40 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:40 INFO - Using env: {'DISPLAY': ':0', 05:06:40 INFO - 'HOME': '/home/cltbld', 05:06:40 INFO - 'LANG': 'en_US.UTF-8', 05:06:40 INFO - 'LANGUAGE': 'en_US:en', 05:06:40 INFO - 'LOGNAME': 'cltbld', 05:06:40 INFO - 'MAIL': '/var/mail/cltbld', 05:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:40 INFO - 'MOZ_NO_REMOTE': '1', 05:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:40 INFO - 'NO_EM_RESTART': '1', 05:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:40 INFO - 'PWD': '/builds/slave/test', 05:06:40 INFO - 'SHELL': '/bin/bash', 05:06:40 INFO - 'SHLVL': '1', 05:06:40 INFO - 'TERM': 'linux', 05:06:40 INFO - 'TMOUT': '86400', 05:06:40 INFO - 'USER': 'cltbld', 05:06:40 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:40 INFO - '_': '/tools/buildbot/bin/python'} 05:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 INFO - Cleaning up... 05:06:40 INFO - Return code: 0 05:06:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:40 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 0x7f1d1f4b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1170ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', '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:40 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:40 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:40 INFO - Using env: {'DISPLAY': ':0', 05:06:40 INFO - 'HOME': '/home/cltbld', 05:06:40 INFO - 'LANG': 'en_US.UTF-8', 05:06:40 INFO - 'LANGUAGE': 'en_US:en', 05:06:40 INFO - 'LOGNAME': 'cltbld', 05:06:40 INFO - 'MAIL': '/var/mail/cltbld', 05:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:40 INFO - 'MOZ_NO_REMOTE': '1', 05:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:40 INFO - 'NO_EM_RESTART': '1', 05:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:40 INFO - 'PWD': '/builds/slave/test', 05:06:40 INFO - 'SHELL': '/bin/bash', 05:06:40 INFO - 'SHLVL': '1', 05:06:40 INFO - 'TERM': 'linux', 05:06:40 INFO - 'TMOUT': '86400', 05:06:40 INFO - 'USER': 'cltbld', 05:06:40 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:40 INFO - '_': '/tools/buildbot/bin/python'} 05:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:40 INFO - Cleaning up... 05:06:40 INFO - Return code: 0 05:06:40 INFO - Running post-action listener: _resource_record_post_action 05:06:40 INFO - Running post-action listener: _start_resource_monitoring 05:06:40 INFO - Starting resource monitoring. 05:06:40 INFO - [mozharness: 2016-09-18 12:06:40.821442Z] Finished create-virtualenv step (success) 05:06:40 INFO - [mozharness: 2016-09-18 12:06:40.822109Z] Running install step. 05:06:40 INFO - Running pre-action listener: _resource_record_pre_action 05:06:40 INFO - Running main action method: install 05:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:41 INFO - Reading from file tmpfile_stdout 05:06:41 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:41 INFO - Reading from file tmpfile_stdout 05:06:41 INFO - Output received: 05:06:41 INFO - Usage: mozinstall [options] installer 05:06:41 INFO - Options: 05:06:41 INFO - -h, --help show this help message and exit 05:06:41 INFO - -d DEST, --destination=DEST 05:06:41 INFO - Directory to install application into. [default: 05:06:41 INFO - "/builds/slave/test"] 05:06:41 INFO - --app=APP Application being installed. [default: firefox] 05:06:41 INFO - mkdir: /builds/slave/test/build/application 05:06:41 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:41 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:57 INFO - Reading from file tmpfile_stdout 05:06:57 INFO - Output received: 05:06:57 INFO - /builds/slave/test/build/application/firefox/firefox 05:06:57 INFO - Running post-action listener: _resource_record_post_action 05:06:57 INFO - [mozharness: 2016-09-18 12:06:57.155503Z] Finished install step (success) 05:06:57 INFO - [mozharness: 2016-09-18 12:06:57.155837Z] Running run-tests step. 05:06:57 INFO - Running pre-action listener: _resource_record_pre_action 05:06:57 INFO - Running main action method: run_tests 05:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:57 INFO - Python 2.7.3 05:06:57 INFO - Return code: 0 05:06:57 INFO - grabbing minidump binary from tooltool 05:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:57 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 0x116df60>, '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 0x116ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:57 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:57 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:57 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:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:57 INFO - Return code: 0 05:06:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:06:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:06:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:57 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-030', '--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:57 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-030 --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:57 INFO - Using env: {'DISPLAY': ':0', 05:06:57 INFO - 'HOME': '/home/cltbld', 05:06:57 INFO - 'LANG': 'en_US.UTF-8', 05:06:57 INFO - 'LANGUAGE': 'en_US:en', 05:06:57 INFO - 'LOGNAME': 'cltbld', 05:06:57 INFO - 'MAIL': '/var/mail/cltbld', 05:06:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:57 INFO - 'MOZ_NO_REMOTE': '1', 05:06:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:57 INFO - 'NO_EM_RESTART': '1', 05:06:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:57 INFO - 'PWD': '/builds/slave/test', 05:06:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:57 INFO - 'SHELL': '/bin/bash', 05:06:57 INFO - 'SHLVL': '1', 05:06:57 INFO - 'TERM': 'linux', 05:06:57 INFO - 'TMOUT': '86400', 05:06:57 INFO - 'USER': 'cltbld', 05:06:57 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474', 05:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:57 INFO - '_': '/tools/buildbot/bin/python'} 05:06:57 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-030', '--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:57 INFO - mozversion application_buildid: 20160918000553 05:06:57 INFO - mozversion application_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 05:06:57 INFO - mozversion application_display_name: Nightly 05:06:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:57 INFO - mozversion application_name: Firefox 05:06:57 INFO - mozversion application_remotingname: firefox 05:06:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:57 INFO - mozversion application_vendor: Mozilla 05:06:57 INFO - mozversion application_version: 51.0a1 05:06:57 INFO - mozversion platform_buildid: 20160918000553 05:06:57 INFO - mozversion platform_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 05:06:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:57 INFO - mozversion platform_version: 51.0a1 05:06:57 INFO - using testdate: 1474200417 05:06:57 INFO - actual date: 1474200417 05:06:57 INFO - starting webserver on 'localhost:35063' 05:06:57 INFO - SUITE-START | Running 2 tests 05:06:57 INFO - TEST-START | dromaeo_css 05:06:57 INFO - operating with platform_type : linux_ 05:06:57 INFO - Initialising browser for dromaeo_css test... 05:06:57 INFO - TEST-INFO | started process 13380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_lyqaF/profile http://localhost:35063/getInfo.html) 05:07:01 INFO - PROCESS | 13380 | __metrics Screen width/height:1600/1200 05:07:01 INFO - PROCESS | 13380 | colorDepth:24 05:07:01 INFO - PROCESS | 13380 | Browser inner width/height: 1024/697 05:07:01 INFO - PROCESS | 13380 | __metrics 05:07:02 INFO - PROCESS | 13380 | 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:02 INFO - PROCESS | 13380 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:02 INFO - PROCESS | 13380 | console.error: PushService: 05:07:02 INFO - PROCESS | 13380 | stateChangeProcessEnqueue: Error transitioning state 05:07:02 INFO - PROCESS | 13380 | UnknownError 05:07:03 INFO - TEST-INFO | 13380: exit 0 05:07:03 INFO - Browser initialized. 05:07:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:03 INFO - TEST-INFO | started process 13465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_lyqaF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:15 INFO - PROCESS | 13465 | RSS: Main: 175230976 05:07:15 INFO - PROCESS | 13465 | 05:07:39 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:07:45 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.06669 seconds. 05:07:59 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:08:02 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:08:06 INFO - PROCESS | 13465 | 1474200486677 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:06 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:08:08 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:08:22 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:08:32 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:08:46 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:08:48 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:08:50 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:08:52 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 05:08:57 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.05 seconds. 05:09:08 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:09:21 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 05:09:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:09:29 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 05:09:37 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:09:39 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 05:09:43 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:09:45 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:09:47 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.15002 seconds. 05:09:58 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:10:17 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:10:19 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:10:19 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35063/tests/dromaeo/cssquery-ext.html) 05:10:21 INFO - PROCESS | 13465 | RSS: Main: 272150528 05:10:21 INFO - PROCESS | 13465 | 05:10:24 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 05:10:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:10:32 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:10:34 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:10:35 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:10:37 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:10:39 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:10:41 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:10:43 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:10:52 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:10:53 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:11:06 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:11:14 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:11:26 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:11:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:11:32 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:11:40 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:11:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:11:52 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:11:54 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:12:05 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:12:20 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 05:12:25 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:12:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:12:31 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:12:32 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 2.05004 seconds. 05:12:49 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:13:03 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:13:05 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:13:19 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:13:23 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-ext.html (next: http://localhost:35063/tests/dromaeo/cssquery-jquery.html) 05:13:24 INFO - PROCESS | 13465 | RSS: Main: 277979136 05:13:24 INFO - PROCESS | 13465 | 05:13:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 05:13:29 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 2.04996 seconds. 05:13:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:13:59 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:14:05 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:14:13 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:14:15 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:14:17 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 05:14:35 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:14:49 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:14:52 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:15:05 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:15:09 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 05:15:24 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:26 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:15:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:30 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:40 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:42 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:15:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:15:46 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:15:55 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:16:13 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:16:20 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:16:26 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35063/tests/dromaeo/cssquery-mootools.html) 05:16:27 INFO - PROCESS | 13465 | RSS: Main: 257024000 05:16:27 INFO - PROCESS | 13465 | 05:16:30 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:16:58 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:17:00 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:17:02 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:17:12 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00352 seconds. 05:17:14 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:17:16 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:17:38 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:17:40 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:17:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:17:53 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:18:04 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:18:06 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:18:14 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:18:49 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:18:51 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:18:53 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:18:59 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:19:01 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:19:12 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:19:15 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:19:18 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:19:19 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:19:25 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:19:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:19:29 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35063/tests/dromaeo/cssquery-prototype.html) 05:19:30 INFO - PROCESS | 13465 | RSS: Main: 219729920 05:19:30 INFO - PROCESS | 13465 | 05:19:46 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:20:25 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:20:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:20:29 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:20:39 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 05:20:41 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:20:43 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:21:03 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:21:04 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 05:21:24 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:21:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:21:30 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:21:32 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:21:34 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:21:36 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:21:37 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 05:21:38 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:21:42 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:21:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:21:56 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:22:11 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:22:25 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:22:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.0028 seconds. 05:22:33 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35063/tests/dromaeo/cssquery-yui.html) 05:22:33 INFO - PROCESS | 13465 | RSS: Main: 219312128 05:22:33 INFO - PROCESS | 13465 | 05:22:40 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:22:50 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:23:14 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:23:18 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:23:20 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:23:31 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:23:33 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:23:39 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:23:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 05:23:46 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:23:47 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:23:49 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:05 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:24:07 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:24:16 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:24:17 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:26 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:24:27 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:33 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:24:35 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:41 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:24:44 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:24:46 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:24:50 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:24:52 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:24:59 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:25:19 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:25:22 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 05:25:28 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 05:25:29 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 2.01671 seconds. 05:25:37 INFO - PROCESS | 13465 | [GFX1-]: Refresh driver waiting for the compositor for 1.06673 seconds. 05:25:37 INFO - PROCESS | 13465 | Cycle 1(1): loaded http://localhost:35063/tests/dromaeo/cssquery-yui.html (next: http://localhost:35063/tests/dromaeo/cssquery-dojo.html) 05:25:37 INFO - PROCESS | 13465 | RSS: Main: 283086848 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | __start_tp_report 05:25:37 INFO - PROCESS | 13465 | _x_x_mozilla_page_load 05:25:37 INFO - PROCESS | 13465 | _x_x_mozilla_page_load_details 05:25:37 INFO - PROCESS | 13465 | |i|pagename|runs| 05:25:37 INFO - PROCESS | 13465 | |0;dojo.html;2543.9491210175793;2565.884535195916;2591.4169311904816;2594.6626938497993;2611.7127116017245;4497.955020449795;4544.204764166266;4653.115907977482;4685.390899183106;4689.718616882989;4593.586577208051;4659.906801863961;4699.600533954618;4704;4764.809407623699;4276.23027854986;4310.159518893817;4324.6972711910175;4333.306670932651;4339.067100573376;4281.207976524337;4328.134373125372;4371.235033869076;4419.359192917042;4437.733735975852;4412.75729834859;4422.823087076513;4430.623397011258;4460.598546130847;4480.641548676114;4197.664186865074;4239.0674051708575;4250.256205164115;4270.679699022586;4288.914221715549;4240.342746874239;4248.426462427564;4257.1485702859245;4262.296721041024;4333.349997500369;4614.215583350839;4614.3770590970125;4641.7911193996015;4642.373279607244;4701.694389864682;4864.854054378375;4970.950290497085;4972.776225069881;4974.154393753034;5027.924581131286;4927.433345165316;4938.678985865943;4998.225275082368;4999.250112483121;5056.544910958031;4681.88295292617;4684.039771846763;4687.742174180385;4688.789004494806;4743.644226683013;5105.744712764347;5116.667416617983;5117.8720531987;5118.744062796845;5183.300254465675;4784.282357646381;4827.589654879304;4832.033593281358;4899.387576552931;4907.582855457326;4936.777844996984;4980.775865086008;4989.90020199594;5011.724355160502;5066.113430149783;5043.4956504349275;5044.848654540369;5096.821611243588;5105.9489405106215;5107.029664363759;4975.179095449211;5035.79856805731;5036.043151801146;5036.992601479646;5097.719625420637;4137.524184718796;4139.110091330378;4255.29787585045;4258;4260.510041345223;3647.507586475845;3686.2811751708696;3699.963000370016;3724.8510059597857;3746.0260332313796;4201.411802347674;4210.368444733253;4213.789310534461;4214.241436541391;4262.957370426318;5030.119729047402;5032.295478632921;5032.446430892599;5032.6225533084435;5085.898282034339;4122.361034039729;4166.937495937564;4167.604077612622;4212.726172798758;4217.030083080908;4496.482904465963;4500.617447516997;4506.571875671806;4527.977360113178;4559.247724125482;4697.201475749131;4706.129366067288;4706.788194531255;4710.623150148049;4761.6904901181315;4842.15262329084;4930.704157750546;4938.43208031081;5013.548780609617;5022.84931452057;4714.882127946828;4723.456802467623;4728.881777955578;4768.070226305837;4784.162771514902;4924.310596516562;4937.111319962298;4937.382827146606;4939.259111133359;5000.824971125993;4821.662483626113;4889.779959901742;4978.079055374767;4995.125852975642;4996.625253105959;4851.902961940812;4887.120318342732;4888.828890988798;4939.604831613534;4948.307236986752;4877.439094504178;4880.731559764247;4880.853574392774;4883.121038213156;4949.084419382426;4647.65142614312;4690.882727931829;4807.927881081857;4813.7352445615825;4861.927071094007;4897.706137631753;4914.975425122851;4943.4562198157455;4965.957148998751;5017.749112544431;4806.807727690853;4833.178359678934;4844.321794948781;4900.8774780630765;4903 05:25:37 INFO - PROCESS | 13465 | |1;ext.html;562.9155626656004;576.8903908257431;583.352478748589;586.3960121075291;591.4499515450632;29850.701492985092;30795.538066929013;30798.230044248907;38352.23295534088;40003.19993600126;39489.8025509872;40099.19801603966;40106.59893401065;40127.59872401275;40156.19687606246;7612.847743045149;7759.379249660042;7766.961165194166;7767.6892924282965;7770.261825126604;7603.125640551324;7692.653830577638;7697.692092316301;7697.692092316301;7698.307152356287;7671.654775535114;7698.846023079535;7710.267524585183;7715.961420192891;7728.459007869451;7680.884786728204;7717.614119294069;7724.150343462271;7725.845483090363;7728.188540203254;7605.847883042308;7631.847363052763;7654.158042615308;7671.309582137578;7672.232776722339;36308;36699.4495082574;36714.632853671654;36743.4488482673;36761.81619091914;3030.9848450757827;3064.5403189521526;3451.8274086295582;3454.2573346730574;3462.982685086584;27034;27226.86386568074;27627.86186069057;27715.722842771516;27779.166625001282;3397.915052123692;3463.0649724574223;3599.4240921452442;3632.763870348419;3663;256190;257713;258743;259536;261502;30653;49368;49682.50317496851;49728.75678108004;49757.75121124371;31673.5248971261;33187.83406083003;33251.16872078169;33255.66744332574;33270.1777188991;13121.868781312256;13209.273489957952;13217.471301148038;13218.471261149447;13221.338933053308;10990.560377584807;11034.558617655204;11084.556617735201;11094.889051109547;11112.611058612909;7631.3131818136635;7680.731174408869;7700.807479813049;7705.653245603961;7708.961455192688;3056.9541456878164;3101.6278046634097;3108.3006323577465;3110.6578276389578;3128.186671465435;843.3632607381388;850.268768858781;862.6592495964059;879.5514287713185;880.3221519430131;25959;26991.460170796476;26994.32514187161;26995.86502067477;27472.45055098927;762.4205603741086;938.9906100938903;965.0735294117726;975.7853272280087;985.4579981010559;13912;14059.507917222849;14122.788158177636;14135.64660883486;14252.287385630678;382391;386964;387488;387960;388596;46892;47194.29208561874;47239.291410628866;47326.053478930226;47380.2892956599;978.2027647467291;1041.770810421706;1138.9772204556027;1192.4157162990246;1195.396324855942;24064.51870962606;27555.586666200423;27820.30449238785;27971.440571188054;28144.43711125807;38878.4168237471;39323.803380982914;39367.606323937536;39447.802760986015;39471.605283946796;287152;290063;290403;291106;291364;23800.166994155123;25153.87423062873;25169;26187.607185892608;26347;39347.213055739296;39347.409788852616;39782.80108599554;40646.1870762589;40904.59095409127;34011.48982765211;34108.82945585256;34175.14562135967;34345.82827085849;34486.655133448345;33656.326873461905;33822;33894.491582625786;34226;34233;33298.667013329556;34142.14644633904;34345.31309373749;34386.656133438344;34597.135071622404;26687;28391.574126388536;30747.231319217197;30886.53670194994;31434.842825785723;23752.524949501258;23767.286981390585;23899.761002389754;23926.760732392453;24086.15698450546 05:25:37 INFO - PROCESS | 13465 | |2;jquery.html;350.39381869366014;359.6026390838122;373.50510573490124;378.27560222174543;390.56842189380734;12340.506379744811;15611.92194039031;16148.434804781835;16662.83337166628;17637.470875873743;16413.17934103293;16459;16865.494035178934;17109.23008464622;17207;4980.178270585349;5044.596432285409;5134.409542902558;5170.741462926858;5213.218017297398;3597.5143355647047;3791.905202369949;5072.670276432039;5130.4356520782685;5160.380754309488;3824.2542704172697;3839.4240863870364;3842.289176502342;5741.368449470555;6039.54703397247;6025.789097381615;6063.454289113979;6094.725737341831;6096.237970253718;6149.385061493859;5258.474152584749;5377.435369286247;5392.514673679388;5401.189821526802;5411.458854114597;20331.491712707153;20496.69254961177;20946.266880659256;20951;20952.790472095236;1858.358866191162;1933.7776155742129;1936.7579052618423;2116.8518203725744;2315.934670051778;12311.69220769479;12816.6795830105;12939.288339141343;13156.605301840864;13719.028073224008;1669.5826043489128;1719.5443207550009;1728.7385456219638;1761.1370428490077;1815.1650240889207;45136.77431612854;69115.30884691139;69117.30882691158;69187;71134.64432677855;8443.240108390151;8564.400491965627;8565.314774818;9818;10219.693409197736;13771.311434428238;13776.380062896993;13783.173009619455;13788.862111378958;13794.862051379558;7181.533200341961;7354.632268386559;7374.631268436664;7590.8861367079535;7619.542827430372;6999.440044796404;7001.684924178322;7167.964160179165;7170.713171473083;7274.381677557361;6053.757849685963;6154.692265386802;6220.129181914535;6290.842728931813;6370.649614271259;1709.8033726121655;1748.4472669902086;1757.7187649975942;1772.7872655281449;1805.142557285279;841.5707988925692;889.63134665474;894.9686760963465;955.617752898846;971.4511301114848;16790.916045419694;17866.91066544659;17881.46355609334;17883;17901.433522916108;841.1168273313119;855.5679381912095;874.790050387915;879.6261588824724;927.536231884058;8972.19250267479;10128.54421551032;10204.489775511194;10247.053059918591;10295.220290521518;360216;366591;382459;383953;386249;66098.33901660921;67895.66052169807;68440.65779671169;68752.31247687459;70151.64924175448;897.9775505612281;911.0843602179767;934.4907025671025;935.3452583191793;937.3251259093441;15411.383544658089;15421.614459638598;15968.121753303216;16112.919435402748;16353.18234088267;33206.667933321325;34271;34899.12752181216;35847.10382240465;35902.281954361286;44686.55313446954;46579.236384778946;46890.76554617205;46891.76554117208;47844.28233576569;6809.557378770364;6833.726650933907;6993.510454268356;7823.178566250462;8073.959630201812;11583.841615838348;11856.822147667619;12015.279083255033;12416.503339866304;12671.366431678194;10097.798044039226;10297.588096476056;10625.840612390975;10832;10919.563217471212;9225.72322830316;10235.94882025615;10353.534090966075;10363.170946324126;10837.67486975392;9452.291078169303;9837.508124593885;10490.895091048991;10596.417197054236;11193.832092518782;7409.259074092548;7796.805079873048;7925.28672419485;7936.166702496154;7964.601769911597;9275.2579793615;9900.20798336117;9957.302988790716;10339.276250662382;10870.293430926964 05:25:37 INFO - PROCESS | 13465 | |3;mootools.html;1244.3156264054767;1350.716349566591;1383.6610030542515;1453.2951518513519;1723.9051852148127;6567.507436942225;7320.3777678897295;7613.352865006467;7627.618619069045;7886.053673559181;7683.884741728864;7757.456978011542;7827.178146294628;7838.25536574026;7845.529268243909;2167.8157356624665;2191.7589065202815;2192;2192.6601376786543;2196.8352373571965;2108.6309895768254;2139.70044193813;2149.322963266574;2152.160657343637;2152.655575107983;2098.7691353951054;2109.177420805887;2117.9046942887608;2119.0782009825775;2147.881866497342;2106.494441334084;2109.4620871677703;2111.0816794694356;2115.122224276931;2115.9682604760787;2055.629986602411;2135.135270215565;2138.1019971611804;2142.9785702142935;2168.3820111268215;7479.887801682979;7725;7727.806804829868;7812.375009999187;7821.100573434071;4283.31466965284;4411.448568928884;4416.160929423431;4422.756748378838;4436.268015777393;2752.270648278208;2859.342351259222;2893.291143669793;2908.5346344584764;2916.0814343481948;1011.1809434358194;1018.3737001743948;1021.4433133942019;1048.5386429970788;1066.568039943824;10286.588536458532;10321.535530901052;10346.53440595175;10355.896441035644;10373.79252414955;3767.698584113319;4015.31739604268;4024.1348110155873;4051.7771522565954;4053.0677944073027;8121.593920303962;8137.511749295061;8147.714829980922;8167;8181.18188181189;4339.34909763538;4340.066885619607;4347.543507931685;4356.477222733292;4365.738055716667;3363.344147891187;3566.054995426214;3676.28312479064;4005.939910901339;4007.078371974404;3192.7605429592872;3237.6438591754886;3331.1672081979505;3361.210115622827;3367.747418943589;1040.4693606260862;1051.2693677893944;1053.4732633683159;1057.043375744952;1065.6536625596711;639.280809089774;730.5981710059552;756.7351427000616;776.9223077692186;801.0947629178991;4691.186814392857;5018.573421259161;5032.622553308516;5098.847034588968;5137.897242055138;407.5150570820714;425.92333379991277;426.3774888662587;429.44387018810716;431.27330448194266;3188.601424821897;3239.7570182236427;3283.2120291130436;3334.216459132102;3402.6597340265776;9118.58966346516;9566.61733530665;9587.18508926749;9593.232541396672;9601.615935362646;9536.761580960392;9554.092361225672;9554.61781528731;9556.617735290649;9560.426374417557;6797.388235058967;7139.714411423589;7165.426765858719;7177.102862142232;7197.712091516385;5186.09243382399;5375.1131063374105;5414.269073675004;5471.589630777628;5596.832095037155;8729.301655867388;8761.780955476164;8907.73276801697;8917.063708310534;9038.593263303299;7882.7241046563095;8529.95735021321;8965.50689712046;8980.057094005035;9128.49793261377;5853.795117170878;6803.217629972419;7122.85754284901;7141.785746427616;7150.5352152109945;7172;7243.420526357984;7269.1640461345505;7299.635018249172;7307.3058059484265;6466.644334561644;6532.41208291256;6639.4356479697835;6739;6768.830779230559;6268;6277.089821975879;6443.130177425987;6465.062565928009;6478.805635830932;5729.770809167587;5887.411258874078;5887.970560147343;5928.9407105928385;5949.7620095197435;4379.934300985424;4423.579759922803;4455.1980643484485;4531.048479819275;4559.224931761542;4402.977985110054;4449.866504004885;4474.843380481668;4544.318352247189;4587.2201725706045 05:25:37 INFO - PROCESS | 13465 | |4;prototype.html;298.5074626865672;300.2269156920928;305.09691313711414;307.26563513203433;310.0311526479751;4822.734749588772;4827.855164345066;4866.926996095052;4873.318705046758;4875.561199492049;4762.6428017898625;4785.952140478594;4811.484382419544;4816.614670826334;4832.613390928726;3597.9820100899524;3682.1531047859007;3726.7018638508853;3762.9811850940705;3808;3789.8484060637543;3795.696344292464;3810.371288737355;3834.118152824852;3842.6550707252377;3767.9058023549355;3768.1333293342545;3777.508923839897;3782.754120982141;3801.1067399161175;3572.1248294167967;3658.9451158232646;3712.814359282025;3773.3020140936687;3817.434851710799;3341.4152523308567;3350.363430948112;3352.6814952579466;3358.8320583970703;3393.117789374756;4712.198926182557;4713.811447542094;4716.3868697069165;4718.315844202606;4719.504452032522;4109.0549173690215;4358.4987726411555;4363.8435814509185;4365.497967733688;4373.715708478939;5003.974185292006;5005.199168133117;5009.6685704006795;5024;5069.884625382447;3674.81625918703;3674.944875826838;3676.761010534307;3684.526189524185;3691.427828686558;5059.974700126476;5219.478052194788;5230.660007099526;5283.656562323436;5302.628815982883;3742.962570374316;3751.9624803751612;3755.192633583738;3776.50905382304;3785.488958990556;3800.7149463790324;3806.276807406584;3807.276617442677;3807.657310841982;3826.215625796705;4310.396544483775;4311.245532031882;4314.395984562164;4315.503717072514;4329.112531930947;4825;5031.496850314939;5035.84892453227;5051.797928082909;5062.772175252123;1306.4382315604382;1323.3251041968665;1323.715401188749;1326.6617012661902;1328.156620545941;830.6594296338593;857.3955361470148;860.5998210831993;864.312871267344;883.1962913748458;4172.7273181679275;4217.91564168721;4315.697901146889;4364.170807546556;4398.802053907518;5197.688138711689;5206.869828254324;5234.476552344735;5240.161574148118;5251;4381.496127945264;4382;4384.714993525411;4389.582989615981;4411.625011874026;2646.338415396151;2723.482538317713;2747.2994386431333;2757;2776.1671498550354;4913.010230856509;4921.729304888266;4929.162042452792;4931.5315045070665;4939.580135688435;5163.715995620202;5364.946350536523;5374.167004114369;5380.488853558984;5389.164679474688;4860.343853579792;4862.614154965818;4899.706017638953;4903.215485522299;4935.605151587864;5192.9740351298005;5195.5843532518065;5197.610179236497;5197.816916894989;5215.608829337739;4976.08898794195;5117.923231151462;5129.820456284012;5139.152039913372;5143.177091665316;4919.803207871788;4920.360353154067;4924.975375123101;4928.112939670894;4945.975270123627;3777.20678657485;3790.5261842269715;3799.3731034379016;3803.0034232028406;3822.311983842824;5064.721440320818;5131.690739167464;5277.656952298088;5295.443978382368;5299.814506492255;4987.576056035277;5006.196529943243;5011.148104822116;5018.272350509229;5026.74866256693;5003.249512573143;5014.34813474246;5016;5059.620528460454;5061.620378471555;4777.060531383094;4959.20652695567;4961.8511444656715;4970.763156579482;5020.974895125647;4850;4990.950090499048;5069.087564238473;5086.567641750493;5100.872478188075;4871.756412179306;4881.243407271879;4888.682235654671;4893.90212195747;4899.191633380452 05:25:37 INFO - PROCESS | 13465 | |5;yui.html;549.1351121982877;574.7126436781609;630.7729217481707;696.7874798186555;733.6075199768123;12981.48074077037;14033.158010519375;14063.929680351584;14080.436782528699;14177.503787367432;12346.81479777804;13470.865291347083;13528.729425411486;13534.932325338359;13577.864221357784;751.1511991449654;767.5241350362783;768.5542385416445;769.7613739740698;770.433731207562;616.7502161624527;634;744.3375395897656;771.8109063279504;777.564563844249;825.3149885594942;825.84721826462;826.0912995704707;827.3422629009951;827.4952279109739;812.4191203289647;813.0365516862478;813.8697808350636;816.1267443835098;821.322409012564;469.1321056046321;478.24437388925764;572.2046355565767;585.844751140948;589.7729374190967;13114.213147211198;13146.802797958038;13150.276734779583;13167.473301067947;13177.802332965013;11667.241629294069;12152.696182595417;12225.572104976372;12360.690982725413;12560.371981400986;11186.496607652674;11432.828507572392;11563.132765042656;11850.407479625985;11948.283103013848;3895.182011777502;3937.645611894943;3952.3083460394605;3957.248122856648;4008.4588580541545;64584.677076614316;64801;64822.675886620265;65248;68832.655836721;9586.904130958601;9606.471644059504;9763.365381250196;9869.407835529892;10151.64469243573;16726.58183545421;16986.660266794595;16988.32046718142;17017.149142542825;17021.14894255307;6446.484281257488;6493.25327587324;6549.705263263165;6559.24568674599;6629.171353580802;3572.5712914450432;3598.1364472526407;3634.055145662147;3668.0279725872665;3674.0080178351695;2549.936251593725;2645.232882464064;2647.669041369829;2652.907148249802;2654.508915850574;465.03041159182806;465.3322482237999;470.1185277604492;476.05740633545776;479.53724655706714;429.39026582253274;436.4587910990349;468.40980364739994;483.30404217926076;503.8513638476658;6946.2011868634745;7200.387967022757;7298.124225093023;7629.504082234637;7870.842583148305;439.5231174175991;465.59493240879976;492.9457759646436;502.8994201159783;505.171518709317;3338.6327503974544;3676;3710.4805327254207;3781.9810900945317;3844.1927195288745;101780.49109754404;102362.48818756007;104701;105369;108449;68122.65938670276;68615;87238.56380718056;89780;90089.9459524323;65210;66962.33037669658;67503.66248168728;68169;69812.30187698058;7209.387202087882;7830.2169783021245;7870;7875.251851074138;8090.676372945016;44129.33805992977;44982;45084.09831803411;45943.77028114838;46937.3571924981;38446.80776596099;47125.528744712115;53297.7335113322;54779;56189.71905140448;10783;11309.71725706864;11316.321020738782;11399.119145340926;11637.243579167327;10540.420276884844;11325.830112548154;11443.942780286045;11682.824757628472;11931.427203733681;11406;11438.428078596204;11770.941145294219;11828.408579570814;12631.178973366701;10878.347299162075;11512.30926144434;11532.192746507999;11680.883191167979;12310.26138431697;10044.397336159855;10081.798364032826;10162;10410.479476026017;10524.052835244865;9105.544722763967;9116.179543841086;9385.530723463937;9425.717228483156;9451.905480945377;9349.766255843659;9472.857907131536;9581.089796469325;9673;9805.313628045968 05:25:37 INFO - PROCESS | 13465 | __end_tp_report 05:25:37 INFO - PROCESS | 13465 | __start_cc_report 05:25:37 INFO - PROCESS | 13465 | _x_x_mozilla_cycle_collect,1111 05:25:37 INFO - PROCESS | 13465 | __end_cc_report 05:25:37 INFO - PROCESS | 13465 | __startTimestamp1474201537394__endTimestamp 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | ------- Summary: start ------- 05:25:37 INFO - PROCESS | 13465 | Number of tests: 6 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#0] dojo.html Cycles:165 Average:4619.16 Median:4743.64 stddev:493.90 (10.4%) stddev-sans-first:467.82 05:25:37 INFO - PROCESS | 13465 | Values: 2543.9 2565.9 2591.4 2594.7 2611.7 4498.0 4544.2 4653.1 4685.4 4689.7 4593.6 4659.9 4699.6 4704.0 4764.8 4276.2 4310.2 4324.7 4333.3 4339.1 4281.2 4328.1 4371.2 4419.4 4437.7 4412.8 4422.8 4430.6 4460.6 4480.6 4197.7 4239.1 4250.3 4270.7 4288.9 4240.3 4248.4 4257.1 4262.3 4333.3 4614.2 4614.4 4641.8 4642.4 4701.7 4864.9 4971.0 4972.8 4974.2 5027.9 4927.4 4938.7 4998.2 4999.3 5056.5 4681.9 4684.0 4687.7 4688.8 4743.6 5105.7 5116.7 5117.9 5118.7 5183.3 4784.3 4827.6 4832.0 4899.4 4907.6 4936.8 4980.8 4989.9 5011.7 5066.1 5043.5 5044.8 5096.8 5105.9 5107.0 4975.2 5035.8 5036.0 5037.0 5097.7 4137.5 4139.1 4255.3 4258.0 4260.5 3647.5 3686.3 3700.0 3724.9 3746.0 4201.4 4210.4 4213.8 4214.2 4263.0 5030.1 5032.3 5032.4 5032.6 5085.9 4122.4 4166.9 4167.6 4212.7 4217.0 4496.5 4500.6 4506.6 4528.0 4559.2 4697.2 4706.1 4706.8 4710.6 4761.7 4842.2 4930.7 4938.4 5013.5 5022.8 4714.9 4723.5 4728.9 4768.1 4784.2 4924.3 4937.1 4937.4 4939.3 5000.8 4821.7 4889.8 4978.1 4995.1 4996.6 4851.9 4887.1 4888.8 4939.6 4948.3 4877.4 4880.7 4880.9 4883.1 4949.1 4647.7 4690.9 4807.9 4813.7 4861.9 4897.7 4915.0 4943.5 4966.0 5017.7 4806.8 4833.2 4844.3 4900.9 4903.0 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#1] ext.html Cycles:180 Average:44775.63 Median:3453.04 stddev:83537.64 (2419.2%) stddev-sans-first:83706.03 05:25:37 INFO - PROCESS | 13465 | Values: 562.9 576.9 583.4 586.4 591.4 29850.7 30795.5 30798.2 38352.2 40003.2 39489.8 40099.2 40106.6 40127.6 40156.2 7612.8 7759.4 7767.0 7767.7 7770.3 7603.1 7692.7 7697.7 7697.7 7698.3 7671.7 7698.8 7710.3 7716.0 7728.5 7680.9 7717.6 7724.2 7725.8 7728.2 7605.8 7631.8 7654.2 7671.3 7672.2 36308.0 36699.4 36714.6 36743.4 36761.8 3031.0 3064.5 3451.8 3454.3 3463.0 27034.0 27226.9 27627.9 27715.7 27779.2 3397.9 3463.1 3599.4 3632.8 3663.0 256190.0 257713.0 258743.0 259536.0 261502.0 30653.0 49368.0 49682.5 49728.8 49757.8 31673.5 33187.8 33251.2 33255.7 33270.2 13121.9 13209.3 13217.5 13218.5 13221.3 10990.6 11034.6 11084.6 11094.9 11112.6 7631.3 7680.7 7700.8 7705.7 7709.0 3057.0 3101.6 3108.3 3110.7 3128.2 843.4 850.3 862.7 879.6 880.3 25959.0 26991.5 26994.3 26995.9 27472.5 762.4 939.0 965.1 975.8 985.5 13912.0 14059.5 14122.8 14135.6 14252.3 382391.0 386964.0 387488.0 387960.0 388596.0 46892.0 47194.3 47239.3 47326.1 47380.3 978.2 1041.8 1139.0 1192.4 1195.4 24064.5 27555.6 27820.3 27971.4 28144.4 38878.4 39323.8 39367.6 39447.8 39471.6 287152.0 290063.0 290403.0 291106.0 291364.0 23800.2 25153.9 25169.0 26187.6 26347.0 39347.2 39347.4 39782.8 40646.2 40904.6 34011.5 34108.8 34175.1 34345.8 34486.7 33656.3 33822.0 33894.5 34226.0 34233.0 33298.7 34142.1 34345.3 34386.7 34597.1 26687.0 28391.6 30747.2 30886.5 31434.8 23752.5 23767.3 23899.8 23926.8 24086.2 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#2] jquery.html Cycles:180 Average:23511.66 Median:193897.64 stddev:61835.53 (31.9%) stddev-sans-first:61984.54 05:25:37 INFO - PROCESS | 13465 | Values: 350.4 359.6 373.5 378.3 390.6 12340.5 15611.9 16148.4 16662.8 17637.5 16413.2 16459.0 16865.5 17109.2 17207.0 4980.2 5044.6 5134.4 5170.7 5213.2 3597.5 3791.9 5072.7 5130.4 5160.4 3824.3 3839.4 3842.3 5741.4 6039.5 6025.8 6063.5 6094.7 6096.2 6149.4 5258.5 5377.4 5392.5 5401.2 5411.5 20331.5 20496.7 20946.3 20951.0 20952.8 1858.4 1933.8 1936.8 2116.9 2315.9 12311.7 12816.7 12939.3 13156.6 13719.0 1669.6 1719.5 1728.7 1761.1 1815.2 45136.8 69115.3 69117.3 69187.0 71134.6 8443.2 8564.4 8565.3 9818.0 10219.7 13771.3 13776.4 13783.2 13788.9 13794.9 7181.5 7354.6 7374.6 7590.9 7619.5 6999.4 7001.7 7168.0 7170.7 7274.4 6053.8 6154.7 6220.1 6290.8 6370.6 1709.8 1748.4 1757.7 1772.8 1805.1 841.6 889.6 895.0 955.6 971.5 16790.9 17866.9 17881.5 17883.0 17901.4 841.1 855.6 874.8 879.6 927.5 8972.2 10128.5 10204.5 10247.1 10295.2 360216.0 366591.0 382459.0 383953.0 386249.0 66098.3 67895.7 68440.7 68752.3 70151.6 898.0 911.1 934.5 935.3 937.3 15411.4 15421.6 15968.1 16112.9 16353.2 33206.7 34271.0 34899.1 35847.1 35902.3 44686.6 46579.2 46890.8 46891.8 47844.3 6809.6 6833.7 6993.5 7823.2 8074.0 11583.8 11856.8 12015.3 12416.5 12671.4 10097.8 10297.6 10625.8 10832.0 10919.6 9225.7 10235.9 10353.5 10363.2 10837.7 9452.3 9837.5 10490.9 10596.4 11193.8 7409.3 7796.8 7925.3 7936.2 7964.6 9275.3 9900.2 9957.3 10339.3 10870.3 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#3] mootools.html Cycles:180 Average:4964.15 Median:4502.95 stddev:2829.33 (62.8%) stddev-sans-first:2823.45 05:25:37 INFO - PROCESS | 13465 | Values: 1244.3 1350.7 1383.7 1453.3 1723.9 6567.5 7320.4 7613.4 7627.6 7886.1 7683.9 7757.5 7827.2 7838.3 7845.5 2167.8 2191.8 2192.0 2192.7 2196.8 2108.6 2139.7 2149.3 2152.2 2152.7 2098.8 2109.2 2117.9 2119.1 2147.9 2106.5 2109.5 2111.1 2115.1 2116.0 2055.6 2135.1 2138.1 2143.0 2168.4 7479.9 7725.0 7727.8 7812.4 7821.1 4283.3 4411.4 4416.2 4422.8 4436.3 2752.3 2859.3 2893.3 2908.5 2916.1 1011.2 1018.4 1021.4 1048.5 1066.6 10286.6 10321.5 10346.5 10355.9 10373.8 3767.7 4015.3 4024.1 4051.8 4053.1 8121.6 8137.5 8147.7 8167.0 8181.2 4339.3 4340.1 4347.5 4356.5 4365.7 3363.3 3566.1 3676.3 4005.9 4007.1 3192.8 3237.6 3331.2 3361.2 3367.7 1040.5 1051.3 1053.5 1057.0 1065.7 639.3 730.6 756.7 776.9 801.1 4691.2 5018.6 5032.6 5098.8 5137.9 407.5 425.9 426.4 429.4 431.3 3188.6 3239.8 3283.2 3334.2 3402.7 9118.6 9566.6 9587.2 9593.2 9601.6 9536.8 9554.1 9554.6 9556.6 9560.4 6797.4 7139.7 7165.4 7177.1 7197.7 5186.1 5375.1 5414.3 5471.6 5596.8 8729.3 8761.8 8907.7 8917.1 9038.6 7882.7 8530.0 8965.5 8980.1 9128.5 5853.8 6803.2 7122.9 7141.8 7150.5 7172.0 7243.4 7269.2 7299.6 7307.3 6466.6 6532.4 6639.4 6739.0 6768.8 6268.0 6277.1 6443.1 6465.1 6478.8 5729.8 5887.4 5888.0 5928.9 5949.8 4379.9 4423.6 4455.2 4531.0 4559.2 4403.0 4449.9 4474.8 4544.3 4587.2 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#4] prototype.html Cycles:180 Average:4202.18 Median:4823.87 stddev:1209.77 (25.1%) stddev-sans-first:1177.15 05:25:37 INFO - PROCESS | 13465 | Values: 298.5 300.2 305.1 307.3 310.0 4822.7 4827.9 4866.9 4873.3 4875.6 4762.6 4786.0 4811.5 4816.6 4832.6 3598.0 3682.2 3726.7 3763.0 3808.0 3789.8 3795.7 3810.4 3834.1 3842.7 3767.9 3768.1 3777.5 3782.8 3801.1 3572.1 3658.9 3712.8 3773.3 3817.4 3341.4 3350.4 3352.7 3358.8 3393.1 4712.2 4713.8 4716.4 4718.3 4719.5 4109.1 4358.5 4363.8 4365.5 4373.7 5004.0 5005.2 5009.7 5024.0 5069.9 3674.8 3674.9 3676.8 3684.5 3691.4 5060.0 5219.5 5230.7 5283.7 5302.6 3743.0 3752.0 3755.2 3776.5 3785.5 3800.7 3806.3 3807.3 3807.7 3826.2 4310.4 4311.2 4314.4 4315.5 4329.1 4825.0 5031.5 5035.8 5051.8 5062.8 1306.4 1323.3 1323.7 1326.7 1328.2 830.7 857.4 860.6 864.3 883.2 4172.7 4217.9 4315.7 4364.2 4398.8 5197.7 5206.9 5234.5 5240.2 5251.0 4381.5 4382.0 4384.7 4389.6 4411.6 2646.3 2723.5 2747.3 2757.0 2776.2 4913.0 4921.7 4929.2 4931.5 4939.6 5163.7 5364.9 5374.2 5380.5 5389.2 4860.3 4862.6 4899.7 4903.2 4935.6 5193.0 5195.6 5197.6 5197.8 5215.6 4976.1 5117.9 5129.8 5139.2 5143.2 4919.8 4920.4 4925.0 4928.1 4946.0 3777.2 3790.5 3799.4 3803.0 3822.3 5064.7 5131.7 5277.7 5295.4 5299.8 4987.6 5006.2 5011.1 5018.3 5026.7 5003.2 5014.3 5016.0 5059.6 5061.6 4777.1 4959.2 4961.9 4970.8 5021.0 4850.0 4991.0 5069.1 5086.6 5100.9 4871.8 4881.2 4888.7 4893.9 4899.2 05:25:37 INFO - PROCESS | 13465 | 05:25:37 INFO - PROCESS | 13465 | [#5] yui.html Cycles:180 Average:17255.43 Median:465.46 stddev:25100.20 (5392.5%) stddev-sans-first:25139.27 05:25:37 INFO - PROCESS | 13465 | Values: 549.1 574.7 630.8 696.8 733.6 12981.5 14033.2 14063.9 14080.4 14177.5 12346.8 13470.9 13528.7 13534.9 13577.9 751.2 767.5 768.6 769.8 770.4 616.8 634.0 744.3 771.8 777.6 825.3 825.8 826.1 827.3 827.5 812.4 813.0 813.9 816.1 821.3 469.1 478.2 572.2 585.8 589.8 13114.2 13146.8 13150.3 13167.5 13177.8 11667.2 12152.7 12225.6 12360.7 12560.4 11186.5 11432.8 11563.1 11850.4 11948.3 3895.2 3937.6 3952.3 3957.2 4008.5 64584.7 64801.0 64822.7 65248.0 68832.7 9586.9 9606.5 9763.4 9869.4 10151.6 16726.6 16986.7 16988.3 17017.1 17021.1 6446.5 6493.3 6549.7 6559.2 6629.2 3572.6 3598.1 3634.1 3668.0 3674.0 2549.9 2645.2 2647.7 2652.9 2654.5 465.0 465.3 470.1 476.1 479.5 429.4 436.5 468.4 483.3 503.9 6946.2 7200.4 7298.1 7629.5 7870.8 439.5 465.6 492.9 502.9 505.2 3338.6 3676.0 3710.5 3782.0 3844.2 101780.5 102362.5 104701.0 105369.0 108449.0 68122.7 68615.0 87238.6 89780.0 90089.9 65210.0 66962.3 67503.7 68169.0 69812.3 7209.4 7830.2 7870.0 7875.3 8090.7 44129.3 44982.0 45084.1 45943.8 46937.4 38446.8 47125.5 53297.7 54779.0 56189.7 10783.0 11309.7 11316.3 11399.1 11637.2 10540.4 11325.8 11443.9 11682.8 11931.4 11406.0 11438.4 11770.9 11828.4 12631.2 10878.3 11512.3 11532.2 11680.9 12310.3 10044.4 10081.8 10162.0 10410.5 10524.1 9105.5 9116.2 9385.5 9425.7 9451.9 9349.8 9472.9 9581.1 9673.0 9805.3 05:25:37 INFO - PROCESS | 13465 | -------- Summary: end -------- 05:25:37 INFO - PROCESS | 13465 | 05:25:38 INFO - TEST-INFO | 13465: exit 0 05:25:38 INFO - TEST-OK | dromaeo_css | took 1120807ms 05:25:38 INFO - TEST-START | kraken 05:25:38 INFO - operating with platform_type : linux_ 05:25:38 INFO - Initialising browser for kraken test... 05:25:38 INFO - TEST-INFO | started process 13780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi2jObx/profile http://localhost:35063/getInfo.html) 05:25:42 INFO - PROCESS | 13780 | __metrics Screen width/height:1600/1200 05:25:42 INFO - PROCESS | 13780 | colorDepth:24 05:25:42 INFO - PROCESS | 13780 | Browser inner width/height: 1024/697 05:25:42 INFO - PROCESS | 13780 | __metrics 05:25:43 INFO - PROCESS | 13780 | 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:43 INFO - PROCESS | 13780 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:43 INFO - PROCESS | 13780 | console.error: PushService: 05:25:43 INFO - PROCESS | 13780 | stateChangeProcessEnqueue: Error transitioning state 05:25:43 INFO - PROCESS | 13780 | UnknownError 05:25:44 INFO - TEST-INFO | 13780: exit 0 05:25:44 INFO - Browser initialized. 05:25:44 INFO - Running cycle 1/1 for kraken test... 05:25:44 INFO - TEST-INFO | started process 13862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi2jObx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:56 INFO - PROCESS | 13862 | RSS: Main: 174723072 05:25:56 INFO - PROCESS | 13862 | 05:25:59 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35063/tests/kraken/driver.html?testName=audio-beat-detection) 05:26:00 INFO - PROCESS | 13862 | RSS: Main: 233910272 05:26:00 INFO - PROCESS | 13862 | 05:26:03 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35063/tests/kraken/driver.html?testName=audio-dft) 05:26:03 INFO - PROCESS | 13862 | RSS: Main: 218472448 05:26:03 INFO - PROCESS | 13862 | 05:26:07 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35063/tests/kraken/driver.html?testName=audio-fft) 05:26:07 INFO - PROCESS | 13862 | RSS: Main: 300167168 05:26:07 INFO - PROCESS | 13862 | 05:26:09 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35063/tests/kraken/driver.html?testName=audio-oscillator) 05:26:10 INFO - PROCESS | 13862 | RSS: Main: 209469440 05:26:10 INFO - PROCESS | 13862 | 05:26:12 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:13 INFO - PROCESS | 13862 | RSS: Main: 213307392 05:26:13 INFO - PROCESS | 13862 | 05:26:16 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35063/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:17 INFO - PROCESS | 13862 | RSS: Main: 250191872 05:26:17 INFO - PROCESS | 13862 | 05:26:20 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35063/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:21 INFO - PROCESS | 13862 | RSS: Main: 244416512 05:26:21 INFO - PROCESS | 13862 | 05:26:24 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35063/tests/kraken/driver.html?testName=json-parse-financial) 05:26:24 INFO - PROCESS | 13862 | RSS: Main: 247324672 05:26:24 INFO - PROCESS | 13862 | 05:26:26 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:27 INFO - PROCESS | 13862 | RSS: Main: 210436096 05:26:27 INFO - PROCESS | 13862 | 05:26:28 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:29 INFO - PROCESS | 13862 | RSS: Main: 218812416 05:26:29 INFO - PROCESS | 13862 | 05:26:31 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:32 INFO - PROCESS | 13862 | RSS: Main: 222179328 05:26:32 INFO - PROCESS | 13862 | 05:26:35 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:35 INFO - PROCESS | 13862 | RSS: Main: 222752768 05:26:35 INFO - PROCESS | 13862 | 05:26:38 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:39 INFO - PROCESS | 13862 | RSS: Main: 221065216 05:26:39 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | Cycle 1(1): loaded http://localhost:35063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35063/tests/kraken/driver.html?testName=ai-astar) 05:26:41 INFO - PROCESS | 13862 | RSS: Main: 272883712 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | __start_tp_report 05:26:41 INFO - PROCESS | 13862 | _x_x_mozilla_page_load 05:26:41 INFO - PROCESS | 13862 | _x_x_mozilla_page_load_details 05:26:41 INFO - PROCESS | 13862 | |i|pagename|runs| 05:26:41 INFO - PROCESS | 13862 | |0;ai-astar;111;117;113;114;117;116;114;115;115;114 05:26:41 INFO - PROCESS | 13862 | |1;audio-beat-detection;161;123;122;163;125;125;124;123;161;125 05:26:41 INFO - PROCESS | 13862 | |2;audio-dft;161;163;163;161;161;162;164;162;161;162 05:26:41 INFO - PROCESS | 13862 | |3;audio-fft;78;77;77;109;77;78;77;77;103;78 05:26:41 INFO - PROCESS | 13862 | |4;audio-oscillator;85;84;113;83;85;83;121;84;84;116 05:26:41 INFO - PROCESS | 13862 | |5;imaging-gaussian-blur;118;117;117;118;118;116;116;117;117;117 05:26:41 INFO - PROCESS | 13862 | |6;imaging-darkroom;146;147;146;146;146;147;146;146;146;147 05:26:41 INFO - PROCESS | 13862 | |7;imaging-desaturate;95;95;95;95;95;94;95;94;95;95 05:26:41 INFO - PROCESS | 13862 | |8;json-parse-financial;65;64;64;64;66;64;64;64;64;64 05:26:41 INFO - PROCESS | 13862 | |9;json-stringify-tinderbox;66;65;65;65;65;67;67;65;69;66 05:26:41 INFO - PROCESS | 13862 | |10;stanford-crypto-aes;82;81;81;92;83;82;82;82;85;82 05:26:41 INFO - PROCESS | 13862 | |11;stanford-crypto-ccm;143;140;133;140;131;130;132;130;169;131 05:26:41 INFO - PROCESS | 13862 | |12;stanford-crypto-pbkdf2;176;182;172;171;173;176;173;173;175;179 05:26:41 INFO - PROCESS | 13862 | |13;stanford-crypto-sha256-iterative;70;69;70;68;69;69;69;69;68;68 05:26:41 INFO - PROCESS | 13862 | __end_tp_report 05:26:41 INFO - PROCESS | 13862 | __start_cc_report 05:26:41 INFO - PROCESS | 13862 | _x_x_mozilla_cycle_collect,1945 05:26:41 INFO - PROCESS | 13862 | __end_cc_report 05:26:41 INFO - PROCESS | 13862 | __startTimestamp1474201601403__endTimestamp 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | ------- Summary: start ------- 05:26:41 INFO - PROCESS | 13862 | Number of tests: 14 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#0] ai-astar Cycles:10 Average:114.60 Median:115.00 stddev:1.84 (1.6%) stddev-sans-first:1.41 05:26:41 INFO - PROCESS | 13862 | Values: 111.0 117.0 113.0 114.0 117.0 116.0 114.0 115.0 115.0 114.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:125.00 stddev:18.30 (14.6%) stddev-sans-first:16.86 05:26:41 INFO - PROCESS | 13862 | Values: 161.0 123.0 122.0 163.0 125.0 125.0 124.0 123.0 161.0 125.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 05:26:41 INFO - PROCESS | 13862 | Values: 161.0 163.0 163.0 161.0 161.0 162.0 164.0 162.0 161.0 162.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#3] audio-fft Cycles:10 Average:83.10 Median:77.00 stddev:12.16 (15.8%) stddev-sans-first:12.76 05:26:41 INFO - PROCESS | 13862 | Values: 78.0 77.0 77.0 109.0 77.0 78.0 77.0 77.0 103.0 78.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:84.00 stddev:15.91 (18.9%) stddev-sans-first:16.55 05:26:41 INFO - PROCESS | 13862 | Values: 85.0 84.0 113.0 83.0 85.0 83.0 121.0 84.0 84.0 116.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 05:26:41 INFO - PROCESS | 13862 | Values: 118.0 117.0 117.0 118.0 118.0 116.0 116.0 117.0 117.0 117.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 05:26:41 INFO - PROCESS | 13862 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:26:41 INFO - PROCESS | 13862 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 05:26:41 INFO - PROCESS | 13862 | Values: 65.0 64.0 64.0 64.0 66.0 64.0 64.0 64.0 64.0 64.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:1.33 (2.0%) stddev-sans-first:1.41 05:26:41 INFO - PROCESS | 13862 | Values: 66.0 65.0 65.0 65.0 65.0 67.0 67.0 65.0 69.0 66.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.00 stddev:3.29 (4.0%) stddev-sans-first:3.46 05:26:41 INFO - PROCESS | 13862 | Values: 82.0 81.0 81.0 92.0 83.0 82.0 82.0 82.0 85.0 82.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:136.50 stddev:11.93 (8.7%) stddev-sans-first:12.51 05:26:41 INFO - PROCESS | 13862 | Values: 143.0 140.0 133.0 140.0 131.0 130.0 132.0 130.0 169.0 131.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.50 stddev:3.40 (1.9%) stddev-sans-first:3.59 05:26:41 INFO - PROCESS | 13862 | Values: 176.0 182.0 172.0 171.0 173.0 176.0 173.0 173.0 175.0 179.0 05:26:41 INFO - PROCESS | 13862 | 05:26:41 INFO - PROCESS | 13862 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 05:26:41 INFO - PROCESS | 13862 | Values: 70.0 69.0 70.0 68.0 69.0 69.0 69.0 69.0 68.0 68.0 05:26:41 INFO - PROCESS | 13862 | -------- Summary: end -------- 05:26:41 INFO - PROCESS | 13862 | 05:26:42 INFO - TEST-INFO | 13862: exit 0 05:26:42 INFO - TEST-OK | kraken | took 64568ms 05:26:42 INFO - SUITE-END | took 1185s 05:26:43 INFO - Completed test suite (00:19:45) 05:26:43 INFO - javascript benchmark 05:26:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2543.9491210175793, 2565.884535195916, 2591.4169311904816, 2594.6626938497993, 2611.7127116017245, 4497.955020449795, 4544.204764166266, 4653.115907977482, 4685.390899183106, 4689.718616882989, 4593.586577208051, 4659.906801863961, 4699.600533954618, 4704.0, 4764.809407623699, 4276.23027854986, 4310.159518893817, 4324.6972711910175, 4333.306670932651, 4339.067100573376, 4281.207976524337, 4328.134373125372, 4371.235033869076, 4419.359192917042, 4437.733735975852, 4412.75729834859, 4422.823087076513, 4430.623397011258, 4460.598546130847, 4480.641548676114, 4197.664186865074, 4239.0674051708575, 4250.256205164115, 4270.679699022586, 4288.914221715549, 4240.342746874239, 4248.426462427564, 4257.1485702859245, 4262.296721041024, 4333.349997500369, 4614.215583350839, 4614.3770590970125, 4641.7911193996015, 4642.373279607244, 4701.694389864682, 4864.854054378375, 4970.950290497085, 4972.776225069881, 4974.154393753034, 5027.924581131286, 4927.433345165316, 4938.678985865943, 4998.225275082368, 4999.250112483121, 5056.544910958031, 4681.88295292617, 4684.039771846763, 4687.742174180385, 4688.789004494806, 4743.644226683013, 5105.744712764347, 5116.667416617983, 5117.8720531987, 5118.744062796845, 5183.300254465675, 4784.282357646381, 4827.589654879304, 4832.033593281358, 4899.387576552931, 4907.582855457326, 4936.777844996984, 4980.775865086008, 4989.90020199594, 5011.724355160502, 5066.113430149783, 5043.4956504349275, 5044.848654540369, 5096.821611243588, 5105.9489405106215, 5107.029664363759, 4975.179095449211, 5035.79856805731, 5036.043151801146, 5036.992601479646, 5097.719625420637, 4137.524184718796, 4139.110091330378, 4255.29787585045, 4258.0, 4260.510041345223, 3647.507586475845, 3686.2811751708696, 3699.963000370016, 3724.8510059597857, 3746.0260332313796, 4201.411802347674, 4210.368444733253, 4213.789310534461, 4214.241436541391, 4262.957370426318, 5030.119729047402, 5032.295478632921, 5032.446430892599, 5032.6225533084435, 5085.898282034339, 4122.361034039729, 4166.937495937564, 4167.604077612622, 4212.726172798758, 4217.030083080908, 4496.482904465963, 4500.617447516997, 4506.571875671806, 4527.977360113178, 4559.247724125482, 4697.201475749131, 4706.129366067288, 4706.788194531255, 4710.623150148049, 4761.6904901181315, 4842.15262329084, 4930.704157750546, 4938.43208031081, 5013.548780609617, 5022.84931452057, 4714.882127946828, 4723.456802467623, 4728.881777955578, 4768.070226305837, 4784.162771514902, 4924.310596516562, 4937.111319962298, 4937.382827146606, 4939.259111133359, 5000.824971125993, 4821.662483626113, 4889.779959901742, 4978.079055374767, 4995.125852975642, 4996.625253105959, 4851.902961940812, 4887.120318342732, 4888.828890988798, 4939.604831613534, 4948.307236986752, 4877.439094504178, 4880.731559764247, 4880.853574392774, 4883.121038213156, 4949.084419382426, 4647.65142614312, 4690.882727931829, 4807.927881081857, 4813.7352445615825, 4861.927071094007, 4897.706137631753, 4914.975425122851, 4943.4562198157455, 4965.957148998751, 5017.749112544431, 4806.807727690853, 4833.178359678934, 4844.321794948781, 4900.8774780630765, 4903.0], "value": 4586.8762041349155, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.9155626656004, 576.8903908257431, 583.352478748589, 586.3960121075291, 591.4499515450632, 29850.701492985092, 30795.538066929013, 30798.230044248907, 38352.23295534088, 40003.19993600126, 39489.8025509872, 40099.19801603966, 40106.59893401065, 40127.59872401275, 40156.19687606246, 7612.847743045149, 7759.379249660042, 7766.961165194166, 7767.6892924282965, 7770.261825126604, 7603.125640551324, 7692.653830577638, 7697.692092316301, 7697.692092316301, 7698.307152356287, 7671.654775535114, 7698.846023079535, 7710.267524585183, 7715.961420192891, 7728.459007869451, 7680.884786728204, 7717.614119294069, 7724.150343462271, 7725.845483090363, 7728.188540203254, 7605.847883042308, 7631.847363052763, 7654.158042615308, 7671.309582137578, 7672.232776722339, 36308.0, 36699.4495082574, 36714.632853671654, 36743.4488482673, 36761.81619091914, 3030.9848450757827, 3064.5403189521526, 3451.8274086295582, 3454.2573346730574, 3462.982685086584, 27034.0, 27226.86386568074, 27627.86186069057, 27715.722842771516, 27779.166625001282, 3397.915052123692, 3463.0649724574223, 3599.4240921452442, 3632.763870348419, 3663.0, 256190.0, 257713.0, 258743.0, 259536.0, 261502.0, 30653.0, 49368.0, 49682.50317496851, 49728.75678108004, 49757.75121124371, 31673.5248971261, 33187.83406083003, 33251.16872078169, 33255.66744332574, 33270.1777188991, 13121.868781312256, 13209.273489957952, 13217.471301148038, 13218.471261149447, 13221.338933053308, 10990.560377584807, 11034.558617655204, 11084.556617735201, 11094.889051109547, 11112.611058612909, 7631.3131818136635, 7680.731174408869, 7700.807479813049, 7705.653245603961, 7708.961455192688, 3056.9541456878164, 3101.6278046634097, 3108.3006323577465, 3110.6578276389578, 3128.186671465435, 843.3632607381388, 850.268768858781, 862.6592495964059, 879.5514287713185, 880.3221519430131, 25959.0, 26991.460170796476, 26994.32514187161, 26995.86502067477, 27472.45055098927, 762.4205603741086, 938.9906100938903, 965.0735294117726, 975.7853272280087, 985.4579981010559, 13912.0, 14059.507917222849, 14122.788158177636, 14135.64660883486, 14252.287385630678, 382391.0, 386964.0, 387488.0, 387960.0, 388596.0, 46892.0, 47194.29208561874, 47239.291410628866, 47326.053478930226, 47380.2892956599, 978.2027647467291, 1041.770810421706, 1138.9772204556027, 1192.4157162990246, 1195.396324855942, 24064.51870962606, 27555.586666200423, 27820.30449238785, 27971.440571188054, 28144.43711125807, 38878.4168237471, 39323.803380982914, 39367.606323937536, 39447.802760986015, 39471.605283946796, 287152.0, 290063.0, 290403.0, 291106.0, 291364.0, 23800.166994155123, 25153.87423062873, 25169.0, 26187.607185892608, 26347.0, 39347.213055739296, 39347.409788852616, 39782.80108599554, 40646.1870762589, 40904.59095409127, 34011.48982765211, 34108.82945585256, 34175.14562135967, 34345.82827085849, 34486.655133448345, 33656.326873461905, 33822.0, 33894.491582625786, 34226.0, 34233.0, 33298.667013329556, 34142.14644633904, 34345.31309373749, 34386.656133438344, 34597.135071622404, 26687.0, 28391.574126388536, 30747.231319217197, 30886.53670194994, 31434.842825785723, 23752.524949501258, 23767.286981390585, 23899.761002389754, 23926.760732392453, 24086.15698450546], "value": 15916.233155409474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.39381869366014, 359.6026390838122, 373.50510573490124, 378.27560222174543, 390.56842189380734, 12340.506379744811, 15611.92194039031, 16148.434804781835, 16662.83337166628, 17637.470875873743, 16413.17934103293, 16459.0, 16865.494035178934, 17109.23008464622, 17207.0, 4980.178270585349, 5044.596432285409, 5134.409542902558, 5170.741462926858, 5213.218017297398, 3597.5143355647047, 3791.905202369949, 5072.670276432039, 5130.4356520782685, 5160.380754309488, 3824.2542704172697, 3839.4240863870364, 3842.289176502342, 5741.368449470555, 6039.54703397247, 6025.789097381615, 6063.454289113979, 6094.725737341831, 6096.237970253718, 6149.385061493859, 5258.474152584749, 5377.435369286247, 5392.514673679388, 5401.189821526802, 5411.458854114597, 20331.491712707153, 20496.69254961177, 20946.266880659256, 20951.0, 20952.790472095236, 1858.358866191162, 1933.7776155742129, 1936.7579052618423, 2116.8518203725744, 2315.934670051778, 12311.69220769479, 12816.6795830105, 12939.288339141343, 13156.605301840864, 13719.028073224008, 1669.5826043489128, 1719.5443207550009, 1728.7385456219638, 1761.1370428490077, 1815.1650240889207, 45136.77431612854, 69115.30884691139, 69117.30882691158, 69187.0, 71134.64432677855, 8443.240108390151, 8564.400491965627, 8565.314774818, 9818.0, 10219.693409197736, 13771.311434428238, 13776.380062896993, 13783.173009619455, 13788.862111378958, 13794.862051379558, 7181.533200341961, 7354.632268386559, 7374.631268436664, 7590.8861367079535, 7619.542827430372, 6999.440044796404, 7001.684924178322, 7167.964160179165, 7170.713171473083, 7274.381677557361, 6053.757849685963, 6154.692265386802, 6220.129181914535, 6290.842728931813, 6370.649614271259, 1709.8033726121655, 1748.4472669902086, 1757.7187649975942, 1772.7872655281449, 1805.142557285279, 841.5707988925692, 889.63134665474, 894.9686760963465, 955.617752898846, 971.4511301114848, 16790.916045419694, 17866.91066544659, 17881.46355609334, 17883.0, 17901.433522916108, 841.1168273313119, 855.5679381912095, 874.790050387915, 879.6261588824724, 927.536231884058, 8972.19250267479, 10128.54421551032, 10204.489775511194, 10247.053059918591, 10295.220290521518, 360216.0, 366591.0, 382459.0, 383953.0, 386249.0, 66098.33901660921, 67895.66052169807, 68440.65779671169, 68752.31247687459, 70151.64924175448, 897.9775505612281, 911.0843602179767, 934.4907025671025, 935.3452583191793, 937.3251259093441, 15411.383544658089, 15421.614459638598, 15968.121753303216, 16112.919435402748, 16353.18234088267, 33206.667933321325, 34271.0, 34899.12752181216, 35847.10382240465, 35902.281954361286, 44686.55313446954, 46579.236384778946, 46890.76554617205, 46891.76554117208, 47844.28233576569, 6809.557378770364, 6833.726650933907, 6993.510454268356, 7823.178566250462, 8073.959630201812, 11583.841615838348, 11856.822147667619, 12015.279083255033, 12416.503339866304, 12671.366431678194, 10097.798044039226, 10297.588096476056, 10625.840612390975, 10832.0, 10919.563217471212, 9225.72322830316, 10235.94882025615, 10353.534090966075, 10363.170946324126, 10837.67486975392, 9452.291078169303, 9837.508124593885, 10490.895091048991, 10596.417197054236, 11193.832092518782, 7409.259074092548, 7796.805079873048, 7925.28672419485, 7936.166702496154, 7964.601769911597, 9275.2579793615, 9900.20798336117, 9957.302988790716, 10339.276250662382, 10870.293430926964], "value": 8260.644658845491, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1244.3156264054767, 1350.716349566591, 1383.6610030542515, 1453.2951518513519, 1723.9051852148127, 6567.507436942225, 7320.3777678897295, 7613.352865006467, 7627.618619069045, 7886.053673559181, 7683.884741728864, 7757.456978011542, 7827.178146294628, 7838.25536574026, 7845.529268243909, 2167.8157356624665, 2191.7589065202815, 2192.0, 2192.6601376786543, 2196.8352373571965, 2108.6309895768254, 2139.70044193813, 2149.322963266574, 2152.160657343637, 2152.655575107983, 2098.7691353951054, 2109.177420805887, 2117.9046942887608, 2119.0782009825775, 2147.881866497342, 2106.494441334084, 2109.4620871677703, 2111.0816794694356, 2115.122224276931, 2115.9682604760787, 2055.629986602411, 2135.135270215565, 2138.1019971611804, 2142.9785702142935, 2168.3820111268215, 7479.887801682979, 7725.0, 7727.806804829868, 7812.375009999187, 7821.100573434071, 4283.31466965284, 4411.448568928884, 4416.160929423431, 4422.756748378838, 4436.268015777393, 2752.270648278208, 2859.342351259222, 2893.291143669793, 2908.5346344584764, 2916.0814343481948, 1011.1809434358194, 1018.3737001743948, 1021.4433133942019, 1048.5386429970788, 1066.568039943824, 10286.588536458532, 10321.535530901052, 10346.53440595175, 10355.896441035644, 10373.79252414955, 3767.698584113319, 4015.31739604268, 4024.1348110155873, 4051.7771522565954, 4053.0677944073027, 8121.593920303962, 8137.511749295061, 8147.714829980922, 8167.0, 8181.18188181189, 4339.34909763538, 4340.066885619607, 4347.543507931685, 4356.477222733292, 4365.738055716667, 3363.344147891187, 3566.054995426214, 3676.28312479064, 4005.939910901339, 4007.078371974404, 3192.7605429592872, 3237.6438591754886, 3331.1672081979505, 3361.210115622827, 3367.747418943589, 1040.4693606260862, 1051.2693677893944, 1053.4732633683159, 1057.043375744952, 1065.6536625596711, 639.280809089774, 730.5981710059552, 756.7351427000616, 776.9223077692186, 801.0947629178991, 4691.186814392857, 5018.573421259161, 5032.622553308516, 5098.847034588968, 5137.897242055138, 407.5150570820714, 425.92333379991277, 426.3774888662587, 429.44387018810716, 431.27330448194266, 3188.601424821897, 3239.7570182236427, 3283.2120291130436, 3334.216459132102, 3402.6597340265776, 9118.58966346516, 9566.61733530665, 9587.18508926749, 9593.232541396672, 9601.615935362646, 9536.761580960392, 9554.092361225672, 9554.61781528731, 9556.617735290649, 9560.426374417557, 6797.388235058967, 7139.714411423589, 7165.426765858719, 7177.102862142232, 7197.712091516385, 5186.09243382399, 5375.1131063374105, 5414.269073675004, 5471.589630777628, 5596.832095037155, 8729.301655867388, 8761.780955476164, 8907.73276801697, 8917.063708310534, 9038.593263303299, 7882.7241046563095, 8529.95735021321, 8965.50689712046, 8980.057094005035, 9128.49793261377, 5853.795117170878, 6803.217629972419, 7122.85754284901, 7141.785746427616, 7150.5352152109945, 7172.0, 7243.420526357984, 7269.1640461345505, 7299.635018249172, 7307.3058059484265, 6466.644334561644, 6532.41208291256, 6639.4356479697835, 6739.0, 6768.830779230559, 6268.0, 6277.089821975879, 6443.130177425987, 6465.062565928009, 6478.805635830932, 5729.770809167587, 5887.411258874078, 5887.970560147343, 5928.9407105928385, 5949.7620095197435, 4379.934300985424, 4423.579759922803, 4455.1980643484485, 4531.048479819275, 4559.224931761542, 4402.977985110054, 4449.866504004885, 4474.843380481668, 4544.318352247189, 4587.2201725706045], "value": 3925.5052942677025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.5074626865672, 300.2269156920928, 305.09691313711414, 307.26563513203433, 310.0311526479751, 4822.734749588772, 4827.855164345066, 4866.926996095052, 4873.318705046758, 4875.561199492049, 4762.6428017898625, 4785.952140478594, 4811.484382419544, 4816.614670826334, 4832.613390928726, 3597.9820100899524, 3682.1531047859007, 3726.7018638508853, 3762.9811850940705, 3808.0, 3789.8484060637543, 3795.696344292464, 3810.371288737355, 3834.118152824852, 3842.6550707252377, 3767.9058023549355, 3768.1333293342545, 3777.508923839897, 3782.754120982141, 3801.1067399161175, 3572.1248294167967, 3658.9451158232646, 3712.814359282025, 3773.3020140936687, 3817.434851710799, 3341.4152523308567, 3350.363430948112, 3352.6814952579466, 3358.8320583970703, 3393.117789374756, 4712.198926182557, 4713.811447542094, 4716.3868697069165, 4718.315844202606, 4719.504452032522, 4109.0549173690215, 4358.4987726411555, 4363.8435814509185, 4365.497967733688, 4373.715708478939, 5003.974185292006, 5005.199168133117, 5009.6685704006795, 5024.0, 5069.884625382447, 3674.81625918703, 3674.944875826838, 3676.761010534307, 3684.526189524185, 3691.427828686558, 5059.974700126476, 5219.478052194788, 5230.660007099526, 5283.656562323436, 5302.628815982883, 3742.962570374316, 3751.9624803751612, 3755.192633583738, 3776.50905382304, 3785.488958990556, 3800.7149463790324, 3806.276807406584, 3807.276617442677, 3807.657310841982, 3826.215625796705, 4310.396544483775, 4311.245532031882, 4314.395984562164, 4315.503717072514, 4329.112531930947, 4825.0, 5031.496850314939, 5035.84892453227, 5051.797928082909, 5062.772175252123, 1306.4382315604382, 1323.3251041968665, 1323.715401188749, 1326.6617012661902, 1328.156620545941, 830.6594296338593, 857.3955361470148, 860.5998210831993, 864.312871267344, 883.1962913748458, 4172.7273181679275, 4217.91564168721, 4315.697901146889, 4364.170807546556, 4398.802053907518, 5197.688138711689, 5206.869828254324, 5234.476552344735, 5240.161574148118, 5251.0, 4381.496127945264, 4382.0, 4384.714993525411, 4389.582989615981, 4411.625011874026, 2646.338415396151, 2723.482538317713, 2747.2994386431333, 2757.0, 2776.1671498550354, 4913.010230856509, 4921.729304888266, 4929.162042452792, 4931.5315045070665, 4939.580135688435, 5163.715995620202, 5364.946350536523, 5374.167004114369, 5380.488853558984, 5389.164679474688, 4860.343853579792, 4862.614154965818, 4899.706017638953, 4903.215485522299, 4935.605151587864, 5192.9740351298005, 5195.5843532518065, 5197.610179236497, 5197.816916894989, 5215.608829337739, 4976.08898794195, 5117.923231151462, 5129.820456284012, 5139.152039913372, 5143.177091665316, 4919.803207871788, 4920.360353154067, 4924.975375123101, 4928.112939670894, 4945.975270123627, 3777.20678657485, 3790.5261842269715, 3799.3731034379016, 3803.0034232028406, 3822.311983842824, 5064.721440320818, 5131.690739167464, 5277.656952298088, 5295.443978382368, 5299.814506492255, 4987.576056035277, 5006.196529943243, 5011.148104822116, 5018.272350509229, 5026.74866256693, 5003.249512573143, 5014.34813474246, 5016.0, 5059.620528460454, 5061.620378471555, 4777.060531383094, 4959.20652695567, 4961.8511444656715, 4970.763156579482, 5020.974895125647, 4850.0, 4990.950090499048, 5069.087564238473, 5086.567641750493, 5100.872478188075, 4871.756412179306, 4881.243407271879, 4888.682235654671, 4893.90212195747, 4899.191633380452], "value": 3819.3379097784264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.1351121982877, 574.7126436781609, 630.7729217481707, 696.7874798186555, 733.6075199768123, 12981.48074077037, 14033.158010519375, 14063.929680351584, 14080.436782528699, 14177.503787367432, 12346.81479777804, 13470.865291347083, 13528.729425411486, 13534.932325338359, 13577.864221357784, 751.1511991449654, 767.5241350362783, 768.5542385416445, 769.7613739740698, 770.433731207562, 616.7502161624527, 634.0, 744.3375395897656, 771.8109063279504, 777.564563844249, 825.3149885594942, 825.84721826462, 826.0912995704707, 827.3422629009951, 827.4952279109739, 812.4191203289647, 813.0365516862478, 813.8697808350636, 816.1267443835098, 821.322409012564, 469.1321056046321, 478.24437388925764, 572.2046355565767, 585.844751140948, 589.7729374190967, 13114.213147211198, 13146.802797958038, 13150.276734779583, 13167.473301067947, 13177.802332965013, 11667.241629294069, 12152.696182595417, 12225.572104976372, 12360.690982725413, 12560.371981400986, 11186.496607652674, 11432.828507572392, 11563.132765042656, 11850.407479625985, 11948.283103013848, 3895.182011777502, 3937.645611894943, 3952.3083460394605, 3957.248122856648, 4008.4588580541545, 64584.677076614316, 64801.0, 64822.675886620265, 65248.0, 68832.655836721, 9586.904130958601, 9606.471644059504, 9763.365381250196, 9869.407835529892, 10151.64469243573, 16726.58183545421, 16986.660266794595, 16988.32046718142, 17017.149142542825, 17021.14894255307, 6446.484281257488, 6493.25327587324, 6549.705263263165, 6559.24568674599, 6629.171353580802, 3572.5712914450432, 3598.1364472526407, 3634.055145662147, 3668.0279725872665, 3674.0080178351695, 2549.936251593725, 2645.232882464064, 2647.669041369829, 2652.907148249802, 2654.508915850574, 465.03041159182806, 465.3322482237999, 470.1185277604492, 476.05740633545776, 479.53724655706714, 429.39026582253274, 436.4587910990349, 468.40980364739994, 483.30404217926076, 503.8513638476658, 6946.2011868634745, 7200.387967022757, 7298.124225093023, 7629.504082234637, 7870.842583148305, 439.5231174175991, 465.59493240879976, 492.9457759646436, 502.8994201159783, 505.171518709317, 3338.6327503974544, 3676.0, 3710.4805327254207, 3781.9810900945317, 3844.1927195288745, 101780.49109754404, 102362.48818756007, 104701.0, 105369.0, 108449.0, 68122.65938670276, 68615.0, 87238.56380718056, 89780.0, 90089.9459524323, 65210.0, 66962.33037669658, 67503.66248168728, 68169.0, 69812.30187698058, 7209.387202087882, 7830.2169783021245, 7870.0, 7875.251851074138, 8090.676372945016, 44129.33805992977, 44982.0, 45084.09831803411, 45943.77028114838, 46937.3571924981, 38446.80776596099, 47125.528744712115, 53297.7335113322, 54779.0, 56189.71905140448, 10783.0, 11309.71725706864, 11316.321020738782, 11399.119145340926, 11637.243579167327, 10540.420276884844, 11325.830112548154, 11443.942780286045, 11682.824757628472, 11931.427203733681, 11406.0, 11438.428078596204, 11770.941145294219, 11828.408579570814, 12631.178973366701, 10878.347299162075, 11512.30926144434, 11532.192746507999, 11680.883191167979, 12310.26138431697, 10044.397336159855, 10081.798364032826, 10162.0, 10410.479476026017, 10524.052835244865, 9105.544722763967, 9116.179543841086, 9385.530723463937, 9425.717228483156, 9451.905480945377, 9349.766255843659, 9472.857907131536, 9581.089796469325, 9673.0, 9805.313628045968], "value": 6282.068565057949, "unit": "score"}], "name": "dromaeo_css", "value": 6200.131258727434, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 113.0, 114.0, 117.0, 116.0, 114.0, 115.0, 115.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 123.0, 122.0, 163.0, 125.0, 125.0, 124.0, 123.0, 161.0, 125.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 161.0, 161.0, 162.0, 164.0, 162.0, 161.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 109.0, 77.0, 78.0, 77.0, 77.0, 103.0, 78.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 113.0, 83.0, 85.0, 83.0, 121.0, 84.0, 84.0, 116.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 118.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 66.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 65.0, 67.0, 67.0, 65.0, 69.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 92.0, 83.0, 82.0, 82.0, 82.0, 85.0, 82.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 133.0, 140.0, 131.0, 130.0, 132.0, 130.0, 169.0, 131.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 182.0, 172.0, 171.0, 173.0, 176.0, 173.0, 173.0, 175.0, 179.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 68.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1542.2, "alertThreshold": 2.0}]} 05:26:43 INFO - Return code: 0 05:26:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:43 INFO - # TBPL SUCCESS # 05:26:43 INFO - Running post-action listener: _resource_record_post_action 05:26:43 INFO - [mozharness: 2016-09-18 12:26:43.201434Z] Finished run-tests step (success) 05:26:43 INFO - Running post-run listener: _resource_record_post_run 05:26:43 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 405069824; Read time: 0; Write time: 328328 05:26:43 INFO - TinderboxPrint: CPU usage
13.1% 05:26:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:26:43 INFO - TinderboxPrint: I/O write bytes / time
405,069,824 / 328,328 05:26:43 INFO - TinderboxPrint: CPU idle
8,336.4 (86.9%) 05:26:43 INFO - TinderboxPrint: CPU user
1,202.3 (12.5%) 05:26:43 INFO - TinderboxPrint: Swap in / out
0 / 0 05:26:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110780416; Read time: 0; Write time: 68988 05:26:43 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 294289408; Read time: 0; Write time: 259340 05:26:43 INFO - Running post-run listener: _upload_blobber_files 05:26:43 INFO - Blob upload gear active. 05:26:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:43 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:43 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:43 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:44 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:45 INFO - (blobuploader) - INFO - Done attempting. 05:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:45 INFO - (blobuploader) - INFO - Done attempting. 05:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:45 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 - Iteration through files over. 05:26:49 INFO - Return code: 0 05:26:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3dbad3b7468d0b1d3819f53576452ec6c26ffed1e288710632bbb968bffac104bd9de9de0045286ab1df814674f42360ef576f7701ed03c69b73493eb075e1d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/438a667cb8f9cb6b88df82d8e0367f652d96ce3f2fedf90b8ae036cdf9856427300c5818974868cf212d550e4e9581274e67797d9ecbf6aac280d926683ef747", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1214ef875c5875cdf21d589cc874c081394dfb601bea2a20028002a3c2bb8cdd3f5c0b049b78b594e77c7ad5cf3d5324dc34820794373261a224f454ee7ae248"} 05:26:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:49 INFO - Contents: 05:26:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3dbad3b7468d0b1d3819f53576452ec6c26ffed1e288710632bbb968bffac104bd9de9de0045286ab1df814674f42360ef576f7701ed03c69b73493eb075e1d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/438a667cb8f9cb6b88df82d8e0367f652d96ce3f2fedf90b8ae036cdf9856427300c5818974868cf212d550e4e9581274e67797d9ecbf6aac280d926683ef747", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1214ef875c5875cdf21d589cc874c081394dfb601bea2a20028002a3c2bb8cdd3f5c0b049b78b594e77c7ad5cf3d5324dc34820794373261a224f454ee7ae248"} 05:26:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:49 INFO - Copying logs to upload dir... 05:26:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.352687 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-18 05:26:48.930251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:48.933352) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 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/3dbad3b7468d0b1d3819f53576452ec6c26ffed1e288710632bbb968bffac104bd9de9de0045286ab1df814674f42360ef576f7701ed03c69b73493eb075e1d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/438a667cb8f9cb6b88df82d8e0367f652d96ce3f2fedf90b8ae036cdf9856427300c5818974868cf212d550e4e9581274e67797d9ecbf6aac280d926683ef747", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1214ef875c5875cdf21d589cc874c081394dfb601bea2a20028002a3c2bb8cdd3f5c0b049b78b594e77c7ad5cf3d5324dc34820794373261a224f454ee7ae248"} 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.011336 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/3dbad3b7468d0b1d3819f53576452ec6c26ffed1e288710632bbb968bffac104bd9de9de0045286ab1df814674f42360ef576f7701ed03c69b73493eb075e1d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/438a667cb8f9cb6b88df82d8e0367f652d96ce3f2fedf90b8ae036cdf9856427300c5818974868cf212d550e4e9581274e67797d9ecbf6aac280d926683ef747", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1214ef875c5875cdf21d589cc874c081394dfb601bea2a20028002a3c2bb8cdd3f5c0b049b78b594e77c7ad5cf3d5324dc34820794373261a224f454ee7ae248"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:48.958135) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:48.958440) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474200362.862111-1818554474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 05:26:49.043892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 05:26:49.044225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 05:26:49.044514) ========= ========= Total master_lag: 0.18 =========