builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-047 starttime: 1474184649.27 results: success (0) buildid: 20160918000553 builduid: e66f368b4db4489a8f57c64eacec42df revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.272955) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.273694) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.273981) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.310668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.310936) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 00:44:09-- 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% 114M=0s 2016-09-18 00:44:09 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.197034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.528391) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.528960) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.090556 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:09.630475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 00:44:09.630874) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 00:44:09,694 truncating revision to first 12 chars 2016-09-18 00:44:09,694 Setting DEBUG logging. 2016-09-18 00:44:09,694 attempt 1/10 2016-09-18 00:44:09,694 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 00:44:10,825 unpacking tar archive at: fx-team-fd81de7deaab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.459616 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 00:44:11.104605) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:11.104977) ========= script_repo_revision: fd81de7deaaba19cb7a899b08da0472e32bd215b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:11.105422) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:11.105748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 00:44:11.116993) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-18 00:44:11.117392) ========= /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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:44:11 INFO - MultiFileLogger online at 20160918 00:44:11 in /builds/slave/test 00:44:11 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 00:44:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:44:11 INFO - {'append_to_log': False, 00:44:11 INFO - 'base_work_dir': '/builds/slave/test', 00:44:11 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 00:44:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:44:11 INFO - 'branch': 'Fx-Team-Non-PGO', 00:44:11 INFO - 'buildbot_json_path': 'buildprops.json', 00:44:11 INFO - 'config_files': ('talos/linux_config.py',), 00:44:11 INFO - 'default_actions': ('clobber', 00:44:11 INFO - 'read-buildbot-config', 00:44:11 INFO - 'download-and-extract', 00:44:11 INFO - 'populate-webroot', 00:44:11 INFO - 'create-virtualenv', 00:44:11 INFO - 'install', 00:44:11 INFO - 'run-tests'), 00:44:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:44:11 INFO - 'download_minidump_stackwalk': True, 00:44:11 INFO - 'download_symbols': 'ondemand', 00:44:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:44:11 INFO - 'tooltool.py': '/tools/tooltool.py', 00:44:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:44:11 INFO - '/tools/misc-python/virtualenv.py')}, 00:44:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:44:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:44:11 INFO - 'installer_path': 'installer.exe', 00:44:11 INFO - 'log_level': 'info', 00:44:11 INFO - 'log_name': 'talos', 00:44:11 INFO - 'log_to_console': True, 00:44:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:44:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:44:11 INFO - 'opt_config_files': (), 00:44:11 INFO - 'pip_index': False, 00:44:11 INFO - 'sps_profile': False, 00:44:11 INFO - 'sps_profile_interval': 0, 00:44:11 INFO - 'suite': 'dromaeojs', 00:44:11 INFO - 'system_bits': '32', 00:44:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:44:11 INFO - 'title': 'talos-linux64-ix-047', 00:44:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:44:11 INFO - 'use_talos_json': True, 00:44:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:44:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:44:11 INFO - 'work_dir': 'build'} 00:44:11 INFO - [mozharness: 2016-09-18 07:44:11.366143Z] Running clobber step. 00:44:11 INFO - Running pre-action listener: _resource_record_pre_action 00:44:11 INFO - Running main action method: clobber 00:44:11 INFO - rmtree: /builds/slave/test/build 00:44:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:44:12 INFO - Running post-action listener: _resource_record_post_action 00:44:12 INFO - [mozharness: 2016-09-18 07:44:12.456730Z] Finished clobber step (success) 00:44:12 INFO - [mozharness: 2016-09-18 07:44:12.456897Z] Running read-buildbot-config step. 00:44:12 INFO - Running pre-action listener: _resource_record_pre_action 00:44:12 INFO - Running main action method: read_buildbot_config 00:44:12 INFO - Using buildbot properties: 00:44:12 INFO - { 00:44:12 INFO - "project": "", 00:44:12 INFO - "product": "firefox", 00:44:12 INFO - "script_repo_revision": "production", 00:44:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 00:44:12 INFO - "repository": "", 00:44:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 00:44:12 INFO - "buildid": "20160918000553", 00:44:12 INFO - "pgo_build": "False", 00:44:12 INFO - "basedir": "/builds/slave/test", 00:44:12 INFO - "buildnumber": 3328, 00:44:12 INFO - "slavename": "talos-linux64-ix-047", 00:44:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 00:44:12 INFO - "platform": "ubuntu64_hw", 00:44:12 INFO - "scheduler": "tests-fx-team-linux64-talos", 00:44:12 INFO - "branch": "fx-team", 00:44:12 INFO - "repo_path": "integration/fx-team", 00:44:12 INFO - "revision": "fd81de7deaaba19cb7a899b08da0472e32bd215b", 00:44:12 INFO - "stage_platform": "linux64", 00:44:12 INFO - "builduid": "e66f368b4db4489a8f57c64eacec42df", 00:44:12 INFO - "slavebuilddir": "test" 00:44:12 INFO - } 00:44:12 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. 00:44:12 INFO - Running post-action listener: _resource_record_post_action 00:44:12 INFO - [mozharness: 2016-09-18 07:44:12.461125Z] Finished read-buildbot-config step (success) 00:44:12 INFO - [mozharness: 2016-09-18 07:44:12.461352Z] Running download-and-extract step. 00:44:12 INFO - Running pre-action listener: _resource_record_pre_action 00:44:12 INFO - Running main action method: download_and_extract 00:44:12 INFO - mkdir: /builds/slave/test/build/tests 00:44:12 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')]} 00:44:12 INFO - trying https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:44:12 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 00:44:12 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 00:44:13 INFO - Downloaded 1361 bytes. 00:44:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:44:13 INFO - Using the following test package requirements: 00:44:13 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:44:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:44:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:44:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'jsshell-linux-x86_64.zip'], 00:44:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:44:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:44:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:44:13 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:44:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:44:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:44:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:44:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:44:13 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 00:44:13 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 00:44:13 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 00:44:13 INFO - Expected file size: 20365610 00:44:17 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 00:44:17 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 00:44:17 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 00:44:17 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 00:44:18 INFO - Expected file size: 20365610 00:44:23 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 00:44:23 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 00:44:23 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 00:44:23 INFO - Expected file size: 14414059 00:44: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')]} 00:44:24 INFO - trying https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:44:24 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 00:44:24 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 00:44:26 INFO - Downloaded 55602959 bytes. 00:44:26 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 00:44:26 INFO - mkdir: /builds/slave/test/properties 00:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 00:44:26 INFO - Contents: 00:44:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ZWpeDrdrStO8u-yJAoJw3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:44:27 INFO - Running post-action listener: _resource_record_post_action 00:44:27 INFO - Running post-action listener: set_extra_try_arguments 00:44:27 INFO - [mozharness: 2016-09-18 07:44:27.626220Z] Finished download-and-extract step (success) 00:44:27 INFO - [mozharness: 2016-09-18 07:44:27.626448Z] Running populate-webroot step. 00:44:27 INFO - Running pre-action listener: _resource_record_pre_action 00:44:27 INFO - Running main action method: populate_webroot 00:44:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:44:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:44:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:44:27 INFO - '--tppagecycles', 00:44:27 INFO - '7'], 00:44:27 INFO - 'tests': ['tsvgm']}}, 00:44:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:44:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:44:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:44:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:44:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:44:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:44:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['damp', 'tps']}, 00:44:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'tests': ['damp', 'tps']}, 00:44:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['dromaeo_dom']}, 00:44:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:44:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['basic_compositor_video']}, 00:44:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:44:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'other-e10s': {'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['a11yr', 00:44:27 INFO - 'ts_paint', 00:44:27 INFO - 'tpaint', 00:44:27 INFO - 'sessionrestore', 00:44:27 INFO - 'sessionrestore_no_auto_restore', 00:44:27 INFO - 'tabpaint']}, 00:44:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['tsvgx', 00:44:27 INFO - 'tsvgr_opacity', 00:44:27 INFO - 'tart', 00:44:27 INFO - 'tscrollx', 00:44:27 INFO - 'cart']}, 00:44:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:44:27 INFO - 'tsvgr_opacity', 00:44:27 INFO - 'tart', 00:44:27 INFO - 'tscrollx', 00:44:27 INFO - 'cart']}, 00:44:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'talos_options': ['--disable-e10s'], 00:44:27 INFO - 'tests': ['tp5o']}, 00:44:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'tests': ['tp5o']}, 00:44:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'talos_options': ['--disable-e10s', 00:44:27 INFO - '--xperf_path', 00:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:44:27 INFO - 'tests': ['tp5n']}, 00:44:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:27 INFO - 'talos_options': ['--xperf_path', 00:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:44:27 INFO - 'tests': ['tp5n']}}, 00:44:27 INFO - 'talos.zip': {'path': '', 00:44:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:44:27 INFO - Running post-action listener: _resource_record_post_action 00:44:27 INFO - [mozharness: 2016-09-18 07:44:27.646577Z] Finished populate-webroot step (success) 00:44:27 INFO - [mozharness: 2016-09-18 07:44:27.646666Z] Running create-virtualenv step. 00:44:27 INFO - Running pre-action listener: _resource_record_pre_action 00:44:27 INFO - Running main action method: create_virtualenv 00:44:27 INFO - Creating virtualenv /builds/slave/test/build/venv 00:44:27 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 00:44:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:44:27 INFO - Using real prefix '/usr' 00:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:44:28 INFO - Installing distribute.............................................................................................................................................................................................done. 00:44:30 INFO - Installing pip.................done. 00:44:30 INFO - Return code: 0 00:44:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:44:30 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')]} 00:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:30 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')]} 00:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:30 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:30 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 00:44:30 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 00:44:30 INFO - Using env: {'DISPLAY': ':0', 00:44:30 INFO - 'HOME': '/home/cltbld', 00:44:30 INFO - 'LANG': 'en_US.UTF-8', 00:44:30 INFO - 'LANGUAGE': 'en_US:en', 00:44:30 INFO - 'LOGNAME': 'cltbld', 00:44:30 INFO - 'MAIL': '/var/mail/cltbld', 00:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:30 INFO - 'MOZ_NO_REMOTE': '1', 00:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:30 INFO - 'NO_EM_RESTART': '1', 00:44:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:30 INFO - 'PWD': '/builds/slave/test', 00:44:30 INFO - 'SHELL': '/bin/bash', 00:44:30 INFO - 'SHLVL': '1', 00:44:30 INFO - 'TERM': 'linux', 00:44:30 INFO - 'TMOUT': '86400', 00:44:30 INFO - 'USER': 'cltbld', 00:44:30 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:30 INFO - '_': '/tools/buildbot/bin/python'} 00:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:31 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 00:44:31 INFO - Cleaning up... 00:44:31 INFO - Return code: 0 00:44:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:44:31 INFO - Using env: {'DISPLAY': ':0', 00:44:31 INFO - 'HOME': '/home/cltbld', 00:44:31 INFO - 'LANG': 'en_US.UTF-8', 00:44:31 INFO - 'LANGUAGE': 'en_US:en', 00:44:31 INFO - 'LOGNAME': 'cltbld', 00:44:31 INFO - 'MAIL': '/var/mail/cltbld', 00:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:31 INFO - 'MOZ_NO_REMOTE': '1', 00:44:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:31 INFO - 'NO_EM_RESTART': '1', 00:44:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:31 INFO - 'PWD': '/builds/slave/test', 00:44:31 INFO - 'SHELL': '/bin/bash', 00:44:31 INFO - 'SHLVL': '1', 00:44:31 INFO - 'TERM': 'linux', 00:44:31 INFO - 'TMOUT': '86400', 00:44:31 INFO - 'USER': 'cltbld', 00:44:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:31 INFO - '_': '/tools/buildbot/bin/python'} 00:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:31 INFO - Downloading/unpacking psutil>=3.1.1 00:44: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 00:44: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 00:44: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 00:44: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 00:44: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 00:44: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 00:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:44:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:44:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:44:33 INFO - Installing collected packages: psutil 00:44:33 INFO - Running setup.py install for psutil 00:44:33 INFO - building 'psutil._psutil_linux' extension 00:44:33 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 00:44:34 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 00:44:34 INFO - building 'psutil._psutil_posix' extension 00:44:34 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 00:44:34 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 00:44:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:44:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:44:34 INFO - Successfully installed psutil 00:44:34 INFO - Cleaning up... 00:44:34 INFO - Return code: 0 00:44:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:34 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 00:44:34 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 00:44:34 INFO - Using env: {'DISPLAY': ':0', 00:44:34 INFO - 'HOME': '/home/cltbld', 00:44:34 INFO - 'LANG': 'en_US.UTF-8', 00:44:34 INFO - 'LANGUAGE': 'en_US:en', 00:44:34 INFO - 'LOGNAME': 'cltbld', 00:44:34 INFO - 'MAIL': '/var/mail/cltbld', 00:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:34 INFO - 'MOZ_NO_REMOTE': '1', 00:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:34 INFO - 'NO_EM_RESTART': '1', 00:44:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:34 INFO - 'PWD': '/builds/slave/test', 00:44:34 INFO - 'SHELL': '/bin/bash', 00:44:34 INFO - 'SHLVL': '1', 00:44:34 INFO - 'TERM': 'linux', 00:44:34 INFO - 'TMOUT': '86400', 00:44:34 INFO - 'USER': 'cltbld', 00:44:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:34 INFO - '_': '/tools/buildbot/bin/python'} 00:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:35 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 00:44:35 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 00:44:35 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 00:44:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:44:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:44:36 INFO - Installing collected packages: mozsystemmonitor 00:44:36 INFO - Running setup.py install for mozsystemmonitor 00:44:37 INFO - Successfully installed mozsystemmonitor 00:44:37 INFO - Cleaning up... 00:44:37 INFO - Return code: 0 00:44:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:37 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:37 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 00:44:37 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 00:44:37 INFO - Using env: {'DISPLAY': ':0', 00:44:37 INFO - 'HOME': '/home/cltbld', 00:44:37 INFO - 'LANG': 'en_US.UTF-8', 00:44:37 INFO - 'LANGUAGE': 'en_US:en', 00:44:37 INFO - 'LOGNAME': 'cltbld', 00:44:37 INFO - 'MAIL': '/var/mail/cltbld', 00:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:37 INFO - 'MOZ_NO_REMOTE': '1', 00:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:37 INFO - 'NO_EM_RESTART': '1', 00:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:37 INFO - 'PWD': '/builds/slave/test', 00:44:37 INFO - 'SHELL': '/bin/bash', 00:44:37 INFO - 'SHLVL': '1', 00:44:37 INFO - 'TERM': 'linux', 00:44:37 INFO - 'TMOUT': '86400', 00:44:37 INFO - 'USER': 'cltbld', 00:44:37 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:37 INFO - '_': '/tools/buildbot/bin/python'} 00:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:37 INFO - Downloading/unpacking jsonschema==2.5.1 00:44: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 00:44: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 00:44: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 00:44: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 00:44: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 00:44: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 00:44:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:44:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:39 INFO - Downloading functools32-3.2.3-2.tar.gz 00:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:44:39 INFO - warning: no files found matching '*.txt' 00:44:39 INFO - no previously-included directories found matching 'build' 00:44:39 INFO - no previously-included directories found matching 'dist' 00:44:39 INFO - no previously-included directories found matching '.git*' 00:44:39 INFO - Installing collected packages: jsonschema, functools32 00:44:39 INFO - Running setup.py install for functools32 00:44:39 INFO - warning: no files found matching '*.txt' 00:44:39 INFO - no previously-included directories found matching 'build' 00:44:39 INFO - no previously-included directories found matching 'dist' 00:44:39 INFO - no previously-included directories found matching '.git*' 00:44:39 INFO - Successfully installed jsonschema functools32 00:44:39 INFO - Cleaning up... 00:44:39 INFO - Return code: 0 00:44:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:44:39 INFO - Using env: {'DISPLAY': ':0', 00:44:39 INFO - 'HOME': '/home/cltbld', 00:44:39 INFO - 'LANG': 'en_US.UTF-8', 00:44:39 INFO - 'LANGUAGE': 'en_US:en', 00:44:39 INFO - 'LOGNAME': 'cltbld', 00:44:39 INFO - 'MAIL': '/var/mail/cltbld', 00:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:39 INFO - 'MOZ_NO_REMOTE': '1', 00:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:39 INFO - 'NO_EM_RESTART': '1', 00:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:39 INFO - 'PWD': '/builds/slave/test', 00:44:39 INFO - 'SHELL': '/bin/bash', 00:44:39 INFO - 'SHLVL': '1', 00:44:39 INFO - 'TERM': 'linux', 00:44:39 INFO - 'TMOUT': '86400', 00:44:39 INFO - 'USER': 'cltbld', 00:44:39 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:39 INFO - '_': '/tools/buildbot/bin/python'} 00:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:44:39 INFO - Cleaning up... 00:44:39 INFO - Return code: 0 00:44:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:44:39 INFO - Using env: {'DISPLAY': ':0', 00:44:39 INFO - 'HOME': '/home/cltbld', 00:44:39 INFO - 'LANG': 'en_US.UTF-8', 00:44:39 INFO - 'LANGUAGE': 'en_US:en', 00:44:39 INFO - 'LOGNAME': 'cltbld', 00:44:39 INFO - 'MAIL': '/var/mail/cltbld', 00:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:39 INFO - 'MOZ_NO_REMOTE': '1', 00:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:39 INFO - 'NO_EM_RESTART': '1', 00:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:39 INFO - 'PWD': '/builds/slave/test', 00:44:39 INFO - 'SHELL': '/bin/bash', 00:44:39 INFO - 'SHLVL': '1', 00:44:39 INFO - 'TERM': 'linux', 00:44:39 INFO - 'TMOUT': '86400', 00:44:39 INFO - 'USER': 'cltbld', 00:44:39 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:39 INFO - '_': '/tools/buildbot/bin/python'} 00:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:40 INFO - Downloading/unpacking blobuploader==1.2.4 00:44:40 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 00:44:40 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 00:44:40 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 00:44:40 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 00:44:40 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 00:44:40 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 00:44:42 INFO - Downloading blobuploader-1.2.4.tar.gz 00:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:44:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:44:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:44:42 INFO - Downloading docopt-0.6.1.tar.gz 00:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:44:42 INFO - Installing collected packages: blobuploader, requests, docopt 00:44:42 INFO - Running setup.py install for blobuploader 00:44:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:44:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:44:42 INFO - Running setup.py install for requests 00:44:43 INFO - Running setup.py install for docopt 00:44:43 INFO - Successfully installed blobuploader requests docopt 00:44:43 INFO - Cleaning up... 00:44:43 INFO - Return code: 0 00:44:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:43 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')]} 00:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:43 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')]} 00:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:43 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:43 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 00:44:43 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 00:44:43 INFO - Using env: {'DISPLAY': ':0', 00:44:43 INFO - 'HOME': '/home/cltbld', 00:44:43 INFO - 'LANG': 'en_US.UTF-8', 00:44:43 INFO - 'LANGUAGE': 'en_US:en', 00:44:43 INFO - 'LOGNAME': 'cltbld', 00:44:43 INFO - 'MAIL': '/var/mail/cltbld', 00:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:43 INFO - 'MOZ_NO_REMOTE': '1', 00:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:43 INFO - 'NO_EM_RESTART': '1', 00:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:43 INFO - 'PWD': '/builds/slave/test', 00:44:43 INFO - 'SHELL': '/bin/bash', 00:44:43 INFO - 'SHLVL': '1', 00:44:43 INFO - 'TERM': 'linux', 00:44:43 INFO - 'TMOUT': '86400', 00:44:43 INFO - 'USER': 'cltbld', 00:44:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:43 INFO - '_': '/tools/buildbot/bin/python'} 00:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:44:43 INFO - Running setup.py (path:/tmp/pip-fXkKeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:44:43 INFO - Running setup.py (path:/tmp/pip-KSEvdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:44:43 INFO - Running setup.py (path:/tmp/pip-nm5271-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:44:43 INFO - Running setup.py (path:/tmp/pip-amFtLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:44:43 INFO - Running setup.py (path:/tmp/pip-ovEXQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:44:43 INFO - Running setup.py (path:/tmp/pip-9O802s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:44:44 INFO - Running setup.py (path:/tmp/pip-x9OaNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:44:44 INFO - Running setup.py (path:/tmp/pip-G3Krp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:44:44 INFO - Running setup.py (path:/tmp/pip-z_Dva2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:44:44 INFO - Running setup.py (path:/tmp/pip-Y4hMIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:44:44 INFO - Running setup.py (path:/tmp/pip-mdFFKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:44:44 INFO - Running setup.py (path:/tmp/pip-oOQ331-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:44:44 INFO - Running setup.py (path:/tmp/pip-XuUk_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:44:44 INFO - Running setup.py (path:/tmp/pip-81wKE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:44 INFO - Running setup.py (path:/tmp/pip-hSgq__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:44:44 INFO - Running setup.py (path:/tmp/pip-BacRpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:44:44 INFO - Running setup.py (path:/tmp/pip-e7FTgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:44:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:44:44 INFO - Running setup.py install for manifestparser 00:44:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:44:44 INFO - Running setup.py install for mozcrash 00:44:45 INFO - Running setup.py install for mozdebug 00:44:45 INFO - Running setup.py install for mozdevice 00:44:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for mozfile 00:44:45 INFO - Running setup.py install for mozhttpd 00:44:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for mozinfo 00:44:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for mozInstall 00:44:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for mozleak 00:44:45 INFO - Running setup.py install for mozlog 00:44:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for moznetwork 00:44:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:44:45 INFO - Running setup.py install for mozprocess 00:44:46 INFO - Running setup.py install for mozprofile 00:44:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:44:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:44:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:44:46 INFO - Running setup.py install for mozrunner 00:44:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:44:46 INFO - Running setup.py install for mozscreenshot 00:44:46 INFO - Running setup.py install for moztest 00:44:46 INFO - Running setup.py install for mozversion 00:44:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:44:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:44:46 INFO - Cleaning up... 00:44:46 INFO - Return code: 0 00:44:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:46 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:46 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 00:44:46 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 00:44:46 INFO - Using env: {'DISPLAY': ':0', 00:44:46 INFO - 'HOME': '/home/cltbld', 00:44:46 INFO - 'LANG': 'en_US.UTF-8', 00:44:46 INFO - 'LANGUAGE': 'en_US:en', 00:44:46 INFO - 'LOGNAME': 'cltbld', 00:44:46 INFO - 'MAIL': '/var/mail/cltbld', 00:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:46 INFO - 'MOZ_NO_REMOTE': '1', 00:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:46 INFO - 'NO_EM_RESTART': '1', 00:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:46 INFO - 'PWD': '/builds/slave/test', 00:44:46 INFO - 'SHELL': '/bin/bash', 00:44:46 INFO - 'SHLVL': '1', 00:44:46 INFO - 'TERM': 'linux', 00:44:46 INFO - 'TMOUT': '86400', 00:44:46 INFO - 'USER': 'cltbld', 00:44:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:46 INFO - '_': '/tools/buildbot/bin/python'} 00:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:44:46 INFO - Running setup.py (path:/tmp/pip-wwiB2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:44:46 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)) 00:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:44:46 INFO - Running setup.py (path:/tmp/pip-rPNKp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:44:46 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)) 00:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:44:46 INFO - Running setup.py (path:/tmp/pip-u28iw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:44:47 INFO - Running setup.py (path:/tmp/pip-VnMgPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:44:47 INFO - Running setup.py (path:/tmp/pip-j2to9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:44:47 INFO - Running setup.py (path:/tmp/pip-HAwgAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:44:47 INFO - Running setup.py (path:/tmp/pip-cS7K1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:44:47 INFO - Running setup.py (path:/tmp/pip-UqXBF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:44:47 INFO - Running setup.py (path:/tmp/pip-AQt_8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:44:47 INFO - Running setup.py (path:/tmp/pip-Y3bK4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:44:47 INFO - Running setup.py (path:/tmp/pip-phGWoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:44:47 INFO - Running setup.py (path:/tmp/pip-sc6oX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:44:47 INFO - Running setup.py (path:/tmp/pip-5GQ3LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:44:47 INFO - Running setup.py (path:/tmp/pip-KqMzrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:47 INFO - Running setup.py (path:/tmp/pip-F6bwkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:47 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)) 00:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:44:47 INFO - Running setup.py (path:/tmp/pip-kJwTqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:44:48 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)) 00:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:44:48 INFO - Running setup.py (path:/tmp/pip-wWtlR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:44:48 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)) 00:44:48 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)) 00:44:48 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)) 00:44:48 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)) 00:44:48 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)) 00:44:48 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)) 00:44:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:44:48 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 00:44:48 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 00:44:48 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 00:44:48 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 00:44:48 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 00:44:48 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 00:44:49 INFO - Downloading blessings-1.6.tar.gz 00:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:44:50 INFO - Installing collected packages: blessings 00:44:50 INFO - Running setup.py install for blessings 00:44:50 INFO - Successfully installed blessings 00:44:50 INFO - Cleaning up... 00:44:50 INFO - Return code: 0 00:44:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:44:50 INFO - Reading from file tmpfile_stdout 00:44:50 INFO - Current package versions: 00:44:50 INFO - argparse == 1.2.1 00:44:50 INFO - blessings == 1.6 00:44:50 INFO - blobuploader == 1.2.4 00:44:50 INFO - docopt == 0.6.1 00:44:50 INFO - functools32 == 3.2.3-2 00:44:50 INFO - jsonschema == 2.5.1 00:44:50 INFO - manifestparser == 1.1 00:44:50 INFO - mozInstall == 1.12 00:44:50 INFO - mozcrash == 0.17 00:44:50 INFO - mozdebug == 0.1 00:44:50 INFO - mozdevice == 0.48 00:44:50 INFO - mozfile == 1.2 00:44:50 INFO - mozhttpd == 0.7 00:44:50 INFO - mozinfo == 0.9 00:44:50 INFO - mozleak == 0.1 00:44:50 INFO - mozlog == 3.4 00:44:50 INFO - moznetwork == 0.27 00:44:50 INFO - mozprocess == 0.23 00:44:50 INFO - mozprofile == 0.28 00:44:50 INFO - mozrunner == 6.12 00:44:50 INFO - mozscreenshot == 0.1 00:44:50 INFO - mozsystemmonitor == 0.3 00:44:50 INFO - moztest == 0.7 00:44:50 INFO - mozversion == 1.4 00:44:50 INFO - psutil == 3.1.1 00:44:50 INFO - requests == 1.2.3 00:44:50 INFO - wsgiref == 0.1.2 00:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:50 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 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:50 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 00:44:50 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 00:44:50 INFO - Using env: {'DISPLAY': ':0', 00:44:50 INFO - 'HOME': '/home/cltbld', 00:44:50 INFO - 'LANG': 'en_US.UTF-8', 00:44:50 INFO - 'LANGUAGE': 'en_US:en', 00:44:50 INFO - 'LOGNAME': 'cltbld', 00:44:50 INFO - 'MAIL': '/var/mail/cltbld', 00:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:50 INFO - 'MOZ_NO_REMOTE': '1', 00:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:50 INFO - 'NO_EM_RESTART': '1', 00:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:50 INFO - 'PWD': '/builds/slave/test', 00:44:50 INFO - 'SHELL': '/bin/bash', 00:44:50 INFO - 'SHLVL': '1', 00:44:50 INFO - 'TERM': 'linux', 00:44:50 INFO - 'TMOUT': '86400', 00:44:50 INFO - 'USER': 'cltbld', 00:44:50 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:50 INFO - '_': '/tools/buildbot/bin/python'} 00:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 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)) 00:44:50 INFO - Cleaning up... 00:44:50 INFO - Return code: 0 00:44:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3afabddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', '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 00:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:44:50 INFO - Using env: {'DISPLAY': ':0', 00:44:50 INFO - 'HOME': '/home/cltbld', 00:44:50 INFO - 'LANG': 'en_US.UTF-8', 00:44:50 INFO - 'LANGUAGE': 'en_US:en', 00:44:50 INFO - 'LOGNAME': 'cltbld', 00:44:50 INFO - 'MAIL': '/var/mail/cltbld', 00:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:50 INFO - 'MOZ_NO_REMOTE': '1', 00:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:44:50 INFO - 'NO_EM_RESTART': '1', 00:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:50 INFO - 'PWD': '/builds/slave/test', 00:44:50 INFO - 'SHELL': '/bin/bash', 00:44:50 INFO - 'SHLVL': '1', 00:44:50 INFO - 'TERM': 'linux', 00:44:50 INFO - 'TMOUT': '86400', 00:44:50 INFO - 'USER': 'cltbld', 00:44:50 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:50 INFO - '_': '/tools/buildbot/bin/python'} 00:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:44:50 INFO - Cleaning up... 00:44:50 INFO - Return code: 0 00:44:50 INFO - Running post-action listener: _resource_record_post_action 00:44:50 INFO - Running post-action listener: _start_resource_monitoring 00:44:50 INFO - Starting resource monitoring. 00:44:50 INFO - [mozharness: 2016-09-18 07:44:50.934565Z] Finished create-virtualenv step (success) 00:44:50 INFO - [mozharness: 2016-09-18 07:44:50.935051Z] Running install step. 00:44:50 INFO - Running pre-action listener: _resource_record_pre_action 00:44:50 INFO - Running main action method: install 00:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:44:51 INFO - Reading from file tmpfile_stdout 00:44:51 INFO - Detecting whether we're running mozinstall >=1.0... 00:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:44:51 INFO - Reading from file tmpfile_stdout 00:44:51 INFO - Output received: 00:44:51 INFO - Usage: mozinstall [options] installer 00:44:51 INFO - Options: 00:44:51 INFO - -h, --help show this help message and exit 00:44:51 INFO - -d DEST, --destination=DEST 00:44:51 INFO - Directory to install application into. [default: 00:44:51 INFO - "/builds/slave/test"] 00:44:51 INFO - --app=APP Application being installed. [default: firefox] 00:44:51 INFO - mkdir: /builds/slave/test/build/application 00:44:51 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'] 00:44:51 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 00:45:07 INFO - Reading from file tmpfile_stdout 00:45:07 INFO - Output received: 00:45:07 INFO - /builds/slave/test/build/application/firefox/firefox 00:45:07 INFO - Running post-action listener: _resource_record_post_action 00:45:07 INFO - [mozharness: 2016-09-18 07:45:07.271235Z] Finished install step (success) 00:45:07 INFO - [mozharness: 2016-09-18 07:45:07.271483Z] Running run-tests step. 00:45:07 INFO - Running pre-action listener: _resource_record_pre_action 00:45:07 INFO - Running main action method: run_tests 00:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:45:07 INFO - Python 2.7.3 00:45:07 INFO - Return code: 0 00:45:07 INFO - grabbing minidump binary from tooltool 00:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:45:07 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 0x1f37f60>, '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 0x1f38d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f391d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:45:07 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 00:45:07 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 00:45:07 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 00:45:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:45:07 INFO - Return code: 0 00:45:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:45:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:45:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:45:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:45:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:45:07 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-047', '--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 00:45:07 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-047 --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 00:45:07 INFO - Using env: {'DISPLAY': ':0', 00:45:07 INFO - 'HOME': '/home/cltbld', 00:45:07 INFO - 'LANG': 'en_US.UTF-8', 00:45:07 INFO - 'LANGUAGE': 'en_US:en', 00:45:07 INFO - 'LOGNAME': 'cltbld', 00:45:07 INFO - 'MAIL': '/var/mail/cltbld', 00:45:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:45:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:07 INFO - 'MOZ_NO_REMOTE': '1', 00:45:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:45:07 INFO - 'NO_EM_RESTART': '1', 00:45:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:07 INFO - 'PWD': '/builds/slave/test', 00:45:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:45:07 INFO - 'SHELL': '/bin/bash', 00:45:07 INFO - 'SHLVL': '1', 00:45:07 INFO - 'TERM': 'linux', 00:45:07 INFO - 'TMOUT': '86400', 00:45:07 INFO - 'USER': 'cltbld', 00:45:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740', 00:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:07 INFO - '_': '/tools/buildbot/bin/python'} 00:45:07 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-047', '--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 00:45:07 INFO - mozversion application_buildid: 20160918000553 00:45:07 INFO - mozversion application_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 00:45:07 INFO - mozversion application_display_name: Nightly 00:45:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:45:07 INFO - mozversion application_name: Firefox 00:45:07 INFO - mozversion application_remotingname: firefox 00:45:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:45:07 INFO - mozversion application_vendor: Mozilla 00:45:07 INFO - mozversion application_version: 51.0a1 00:45:07 INFO - mozversion platform_buildid: 20160918000553 00:45:07 INFO - mozversion platform_changeset: fd81de7deaaba19cb7a899b08da0472e32bd215b 00:45:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:45:07 INFO - mozversion platform_version: 51.0a1 00:45:07 INFO - using testdate: 1474184707 00:45:07 INFO - actual date: 1474184707 00:45:07 INFO - starting webserver on 'localhost:47514' 00:45:07 INFO - SUITE-START | Running 2 tests 00:45:07 INFO - TEST-START | dromaeo_css 00:45:07 INFO - operating with platform_type : linux_ 00:45:07 INFO - Initialising browser for dromaeo_css test... 00:45:07 INFO - TEST-INFO | started process 3326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXVii4h/profile http://localhost:47514/getInfo.html) 00:45:12 INFO - PROCESS | 3326 | __metrics Screen width/height:1600/1200 00:45:12 INFO - PROCESS | 3326 | colorDepth:24 00:45:12 INFO - PROCESS | 3326 | Browser inner width/height: 1024/697 00:45:12 INFO - PROCESS | 3326 | __metrics 00:45:13 INFO - PROCESS | 3326 | 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" 00:45:13 INFO - PROCESS | 3326 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:45:14 INFO - PROCESS | 3326 | console.error: PushService: 00:45:14 INFO - PROCESS | 3326 | stateChangeProcessEnqueue: Error transitioning state 00:45:14 INFO - PROCESS | 3326 | UnknownError 00:45:15 INFO - TEST-INFO | 3326: exit 0 00:45:15 INFO - Browser initialized. 00:45:15 INFO - Running cycle 1/1 for dromaeo_css test... 00:45:15 INFO - TEST-INFO | started process 3407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXVii4h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:45:27 INFO - PROCESS | 3407 | RSS: Main: 175292416 00:45:27 INFO - PROCESS | 3407 | 00:45:31 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.15003 seconds. 00:45:37 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 00:45:53 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 00:45:57 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:46:01 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 00:46:12 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 00:46:18 INFO - PROCESS | 3407 | 1474184778446 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] 00:46:25 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 00:46:34 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 00:46:39 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 00:46:41 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:46:58 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:47:01 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.06669 seconds. 00:47:15 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 00:47:18 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:47:20 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 00:47:27 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.08335 seconds. 00:47:29 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:47:35 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:47:37 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:47:58 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.15001 seconds. 00:48:02 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:48:04 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:48:12 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:48:15 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 00:48:30 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:48:32 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47514/tests/dromaeo/cssquery-ext.html) 00:48:33 INFO - PROCESS | 3407 | RSS: Main: 271867904 00:48:33 INFO - PROCESS | 3407 | 00:48:36 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:48:41 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:48:48 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:48:57 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:49:06 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:49:14 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:49:24 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:49:34 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:49:36 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:49:44 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:49:46 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:49:56 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:49:58 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:50:00 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:50:13 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:50:14 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:50:21 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 00:50:30 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 00:51:05 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:51:09 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:51:21 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:51:34 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 00:51:35 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-ext.html (next: http://localhost:47514/tests/dromaeo/cssquery-jquery.html) 00:51:36 INFO - PROCESS | 3407 | RSS: Main: 276070400 00:51:36 INFO - PROCESS | 3407 | 00:51:39 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 00:51:49 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:52:21 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:52:23 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:52:27 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:52:31 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:52:35 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 00:52:40 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:52:43 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:52:45 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:52:51 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:53:12 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:53:21 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:53:22 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:53:26 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:53:40 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:53:49 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:53:52 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:53:54 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:53:58 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:54:00 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:54:04 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:54:06 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:54:22 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:54:38 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:54:38 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47514/tests/dromaeo/cssquery-mootools.html) 00:54:39 INFO - PROCESS | 3407 | RSS: Main: 219328512 00:54:39 INFO - PROCESS | 3407 | 00:54:42 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:54:47 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:55:26 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00167 seconds. 00:55:31 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:55:38 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:55:52 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:56:43 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 00:56:53 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:57:01 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:57:05 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:57:07 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:57:09 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:57:27 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:57:36 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:57:41 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:57:41 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47514/tests/dromaeo/cssquery-prototype.html) 00:57:42 INFO - PROCESS | 3407 | RSS: Main: 219848704 00:57:42 INFO - PROCESS | 3407 | 00:58:08 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:58:20 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:58:32 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:58:35 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:58:57 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:59:22 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:59:29 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 00:59:29 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 00:59:40 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03331 seconds. 00:59:46 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 01:00:12 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 01:00:15 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 01:00:25 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:00:28 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 01:00:33 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 01:00:35 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:00:45 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47514/tests/dromaeo/cssquery-yui.html) 01:00:45 INFO - PROCESS | 3407 | RSS: Main: 222175232 01:00:45 INFO - PROCESS | 3407 | 01:00:51 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 01:00:52 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 01:00:53 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 2.03335 seconds. 01:00:54 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:00:56 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:01:01 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 01:01:08 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 01:01:32 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:01:34 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 01:02:05 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:02:16 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:02:17 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:02:25 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 01:02:31 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 01:02:33 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:02:40 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 01:02:41 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:02:43 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:02:56 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 01:02:58 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:03:06 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 01:03:14 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 01:03:22 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 01:03:24 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 01:03:42 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 01:03:44 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 01:03:45 INFO - PROCESS | 3407 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 01:03:49 INFO - PROCESS | 3407 | Cycle 1(1): loaded http://localhost:47514/tests/dromaeo/cssquery-yui.html (next: http://localhost:47514/tests/dromaeo/cssquery-dojo.html) 01:03:49 INFO - PROCESS | 3407 | RSS: Main: 323129344 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | __start_tp_report 01:03:49 INFO - PROCESS | 3407 | _x_x_mozilla_page_load 01:03:49 INFO - PROCESS | 3407 | _x_x_mozilla_page_load_details 01:03:49 INFO - PROCESS | 3407 | |i|pagename|runs| 01:03:49 INFO - PROCESS | 3407 | |0;dojo.html;2556.5653838847393;2570.871456427178;2601.7918566514677;2619.2927909464447;2622.436176222112;4502.707324023936;4598.103369842882;4694.718316900984;4713.528647135285;4731.739754313516;4605.332226827117;4625.976870115653;4630.490646028934;4661.37071495348;4663.720176789395;4226.133642603267;4282.74303541787;4287.656987440997;4338.544452832454;4369.169857727037;4348.4999225089205;4365.039691267916;4367.978160109195;4406.515283318832;4463.486699029604;4354.216241076605;4378.102488989765;4385.8464953726625;4496.842610508633;4497.078098989716;4382.0797632497215;4399.054203346264;4407.713498622579;4447.755373454443;4464.754438505881;4179.561146079679;4210.073783767566;4224.134052519226;4232;4314.503832059322;4657.906841863144;4660.021395506948;4749.62003039756;4754.263089221176;4758.214894542396;4935.210366341368;4976.377952755905;5021.221710634857;5023.974880125613;5089.949100509021;5048.873778155576;5051.545360917535;5061.063703214881;5063.26582645518;5119.2321151827155;4562.817487300537;4613.792379342937;4656.627469802475;4691.835785747482;4709.434867815884;5096.719680417613;5126.487351264918;5136.357955255593;5138.3577052868395;5201.193814958688;4730.219513780256;4931.950680493221;4936.555709986047;4940.382452193476;4940.827071052568;4847.587955023862;4957.950420495749;5018.1218286800395;5029.748512574357;5074.3657042869645;4991.80032798684;5001.799928002912;5031.371078615173;5036.043151801146;5061.468545802712;5008.12357837377;5008.323876276729;5013.523715247045;5021.548060674557;5084.186530155157;4092.3656833190307;4125.958740412558;4127.133302006612;4172.08214192877;4236.597523235288;3717.498137751423;3720.1815600567834;3757.5678796938155;3766.0396598867114;3785.640364165455;4265.381519679691;4269;4270.316749320156;4311.353297005411;4325.307950727932;4841.95158048415;4906.2640603909695;4910.140725373046;5077.695338279715;5082.822101226439;4209.97895010523;4257.57424257578;4268.658507319407;4322.69741118125;4325.437693099877;4538.704984176018;4542.682012259176;4546.1135078659345;4549.7042692224895;4604.654650901129;4722.268048452495;4763.118823017753;4773.570378665937;4775.403074615673;4828.855134345975;4930.457649658463;4999.125153098266;5033.597312215087;5085.847424577269;5093.363329583802;4795.856124316276;4797.112534181188;4801.5438533339275;4841.588464980376;4859.538343857328;4979.775910083982;4993.925091123563;4996.15065438869;4997.100521906092;5056.039352523009;4847.981923795901;4905.950940490692;4941.209406495087;4976.651634385558;5084;4836.177849765619;4837.7581120944515;4841.733704646278;4901.5588597026435;4907.165781817171;4852.296417019442;4854.490278520704;4854.83008094715;4858.076965376567;4863.781129849236;4674.906501870011;4735;4766.022965292037;4784.186688262934;4840.6127509799835;4908.092002979461;4918.803247870045;4931.211006239128;4987.451380348231;4988.575971042357;4807.879803004953;4816.229403295456;4820.807167713252;4879.04858552593;4881.048195601824 01:03:49 INFO - PROCESS | 3407 | |1;ext.html;564.7515093358921;565.0789213581863;581.3111462916441;587.3040447070222;589.6933594530844;28148.718512814867;28911.710882891166;30992.690073099264;31172.376552468937;38369.424458633075;40793.18413631726;41467.37798933018;41615;41622.791886040526;41632.58367416325;7621.695132194719;7753.767386978399;7759.379249660042;7767.456278060538;7769.689212431496;7656.808579785495;7745.6127193640095;7749.418793590475;7753.573553454557;7754.689812407497;7796.337311328544;7828.725994590191;7844.960775196116;7845.725399611015;7846.254605812439;7756.767296981071;7781.688732450695;7796.376289896823;7797.57113358765;7800.492967957065;7540.095188577406;7559.773206803805;7603.581803000833;7759.301662850314;7767.72812951544;37348.253034939145;37967.81016094902;38048.80975595104;38084.80957595222;38104.42843357352;2777.7638900693264;2960.7335339819306;3156.875884148598;3177.682231776827;3500.8949731508096;28510.572341414896;28615.28461788451;29086.70913290861;29159.416811663647;30506.08481745551;3470.1845066409373;3493.3013397320638;3544.4506101554302;3549.8757543485854;3588.67701906827;245497;246373;246758;247054;247265.7636711823;52386.73806630942;52740.73629631904;52768.472315276354;52775.73612131915;52802.73598631982;32938.341233175204;33998.150046249044;34005.659943400744;35598.46602300968;54678.72660636751;14991.625209369853;15112.395504179709;15149.394024239127;15159.545213643609;15162.545123646309;11496.080313574894;11556.94221528887;11570.94214528922;11603.535858565563;11632.18574699763;17476.30094796194;17567.82432175662;17642.38251661186;17718.379856704953;17785.110744462727;6855.897161542581;6871.312868713088;6879.793606191723;6974.616396098263;7061.646917654097;785.6464590934103;888.5734847273345;932.3314249363851;942.458086600194;986.0878687214298;26539.601905971427;28000.71999280022;28202.20562504338;28246;28467.57298640522;751.2562563062529;938.8920274168421;988.8071825993862;993.145894530703;1000.8690180096445;13522.32388380577;14429.639259018608;14613.92693036528;14817.70364592702;14869.330880110421;379526;382419;383761;384425;386657;48814.75592622014;49488.752556237705;49532.75233623809;49562.50437495651;49612;1084.2572837606265;1117.9049780768726;1137.2096393006946;1186.9940650296694;1213.514594162342;26537.33656658599;29407.26481837971;29435.264118397212;29951.850240748656;29955.550666739582;41550;41697.58302416937;42010.78994605008;42097.158056839304;42114.683692395985;242393;243555;244955;244978;245417;24416.87791561031;24544.140955066487;25146.62280065764;25387.36531586725;26410.603840942786;40585;40591;40869.79565102155;41533.79233103815;41679;34312.485312720826;34428.655713442546;34779.65220347764;35123.82438087793;35126;34994.300113998084;35112.82443587766;35177;35553.288934220654;35786;34873;35108.12229694278;35334.46998295079;35746.463803042454;35815.10462238361;30015.699843002163;32291.677083229806;32378;32529.83735081404;32636.673633263363;24713;25001;25025;25110.12114575981;25197.496050079262 01:03:49 INFO - PROCESS | 3407 | |2;jquery.html;348.67573156964045;363.88355726167634;367.10609665464597;372.43018182181265;390.97564381320944;12312.076594255434;13237.602871913847;14074.155550666967;16425.83574164258;17125.82874171258;15465.381384744596;15942.521724348288;16142.192890355494;16607.584810379747;17012.48962531123;4501.414816073906;4788.664793464459;4816.97591512042;4821.325014497973;4827.082854257697;3456.360573293936;4745.90508189836;4822.445418776833;4823.445303790057;4826.3001864729595;3665.3585622516084;5320.467953204688;5560.082586373263;5582.302212223472;5681.11942648888;5438.320209973754;5584.46947539983;5649.604527683064;5651.717414129298;5658.8302350929125;5446.700431476307;5450.264214331054;5453.100238460649;5458.099413596752;5458.890822183574;12579.37103144839;13192.604221873264;20412.79587204124;20574.588508229903;20604.690929636068;1870.8783929044705;1896.6680830854546;2026.4731169895786;2033.6441122803599;2368.4907744834773;8995.640174393016;9243.815123697488;9277.118673725985;9626.75933101671;9739.123478886864;1665.1757380096808;1761.5508045448457;1782.803891571926;1793.219949322036;1811.9275228990825;41291;75573;75723;77146.22853771503;84626.57686711589;16175.757363639555;16367.42714005004;16497.587560310847;16558.751618725728;17105.315787368614;29377.85887469684;29439.558406623917;29590.852045739633;29635.55546666802;29663.110106696833;8720.12798720123;8802.383833131747;8827.779305517284;8838.779030524289;9100.22648074921;7018.9298107019295;7162.713491460283;7212.891806622905;7245;7494.737684181027;8035.316998055114;8098.878516822252;8358.540280284642;8554.61504232311;8582.313414926792;3186.5490002837023;3208.903732888017;3253.21001033793;3270.73834093272;3293.604767427924;847.4830353484369;860.7718954477068;874.2437791310471;885.8051228729798;897.3494216692845;16024.19879006045;16514.2568584414;16655.91672041632;16782.496525104267;17568.56078598045;835.8286551256978;851.5997481183833;854.047736773493;867.8568036274071;927.0868194828089;9492.525373731285;9583.520823958914;9598.376105553116;9702.70891873245;9781.74494095071;363430;364094;376193;376254;376338;74254.62872685603;75850.62074689701;75877.6206118966;76484.61757691287;77206;876.0626130040795;893.2764460786653;907.7412937312835;910.7113434490254;915.4461550761827;14764.33560489802;14888.702225955636;15050.548483545512;15940.043597384194;16551.337946482487;39543.80228098841;40122.984626921636;40169.1966160681;40239.79880100581;40502.18995620012;50743.985120296646;52121.47878521318;52807;53022.46977530175;53700.46299536955;7478.813029674084;7767.844643107219;7829.882551761614;7925.207479252029;8385.580720963806;9686.70939871805;10840.457977100956;11010.669679909615;11364.94317528407;11730.648080557597;9934.950325248328;10178.982101790058;10227.897721022695;10278.434686092336;10493.790124197321;9223.953880230556;9479.383840050376;9613.279004074582;9685.079917407835;9825.722656054668;9764.170045546209;9999.208297047133;10115.140213081677;10164.644237451654;10329.896701032894;6822.761203357859;7001.474889383215;7070.505064645426;7388.187299397169;7528;8450.957745211235;9914.900850991686;9945.602175913173;10230.181585473294;10277.280590358603 01:03:49 INFO - PROCESS | 3407 | |3;mootools.html;1240.7766602011634;1373.4368908747415;1393.9930300348499;1477.4090363854457;1680.605057811414;6615.371539703727;7506.024216851809;7716.575588342639;7751.302382785549;7926.841463170733;7790.727324543628;7836.333911617505;7838.72564460244;7846.882296765539;7862.882056769153;2112.186808078893;2123.925662601809;2134.466383404149;2137.0703743871386;2139.0267428320153;2156.1698745982753;2192.331338941622;2215.667649852519;2219.3563866478703;2220.3893929169526;2132.2004248406847;2132.509522809747;2134.8612340197915;2137.487003119248;2197.516546359798;2132.3283165802723;2135.39141344716;2136.0601335412525;2139.187108898624;2140.3044010696585;2050.0569737920573;2094.476380904774;2100.4958809885516;2100.7479102507646;2107.4099252209407;7781.299683028555;7826.295633393022;7846.254605812439;7922.841543169162;7938.960305198437;4194.454720886296;4354.172707185625;4355.3031514957775;4367.432233809616;4393.033532622818;2692.9057482988196;2831.7593004594632;2882.0777351247607;2884.322184286691;2888.393437378153;961.4519723757461;963.3641796414333;980.5734505490063;981.5435822342645;987.0573602209872;10702.090322322612;10755.354678719303;10783.83824242629;10787.5684972601;10795.78408431827;4007.7595344278852;4015.9196816063513;4169.874903752831;4181.372794080851;4210.136921930997;8382.203690649378;8438.915610843937;8447.493150410872;8448.07071222165;8453.154684531497;4467.977660111678;4470.709403888737;4473.664475164311;4474.060447306102;4475.239209334421;3540.628234035389;3679.3377192105677;4069.064115253508;4151.066010147692;4155.314373128461;3319.55186049885;3333.916652083669;3506.6855875122296;3514.525539052195;3514.9648503514636;1056.6143357674393;1062.1874266186373;1067.8024565455416;1071.5767271927546;1088.5373716170595;708.6846353372699;709.1950636028146;721.2318880392365;725.989110163348;768.4812751392788;4457.2199865023495;4509.819607215674;4605.309203619417;4682.672212945061;4694.694844835075;416.16351134220434;417.9602937720912;418.26803094584477;422.47612959929467;424.88528097413524;3063.846807659608;3118.703723146343;3123.2191952012;3156.0847354267007;3167.3665266946705;9257;9608.90391096094;9662.08210220028;9693.903060969442;9711.611535538499;9549.570269337897;9603.423794572209;9611.663591774255;9644.855327170098;9706.174975127193;7201.243869393743;7289.307515785992;7302.671379788028;7316.414686825041;7318;4472.686911916265;4512.819487220474;4552.658550608784;4577.542245775396;4648.279516674921;8702.390832641908;8716.38985271025;8741.300695944183;8752.649894004424;8847.601857916281;8901.33240006989;8960.462372257685;9052.321075919464;9109.90890091117;9134.634614615341;5469.398366179637;5642.520385767091;6100.481459075851;6197.566170368031;6204.410580994799;7044.189918159273;7278.09023872016;7281.927180728125;7348.265173482609;7502.399808015237;6707.29573394806;6838.179418469816;6967.407770339578;7016.5790052597;7161.57030578167;6928.203256625553;6936;7002.7899163025195;7160.176579693193;7273.9636301818155;5899.144624029406;5938.554608404301;5965.164876917322;6003.039513677791;6006.579539432198;4341.913161736811;4377.4528183977;4511.571400716926;4575.222212223864;4595.310703394518;4332.956670433256;4347.804348804375;4433.46798384196;4443.933340999953;4492.326151077234 01:03:49 INFO - PROCESS | 3407 | |4;prototype.html;294.75535305696275;295.95116805727054;297.92105092150575;298.94021195760854;304.8597645083262;4740.700760131338;4808.110499557584;4811.831585894494;4822.782974766135;4841.854744357666;4736.778990286603;4746.62027037837;4763.571278584926;4792.496787837279;4796.496367881375;3763.6989040876733;3774.698024158061;3792.4880141181;3803.8478460861534;3809.638084381993;3498.9300213995707;3803.1633040731135;3810.5617853946737;3812.3328417526823;3822.8661996830115;3680.5215322008103;3717.2008018276065;3717.9365452174034;3723.218124193909;3733.5893051764283;3682.8895133146048;3684.6683798458;3697.4084146536425;3698.445233215026;3708.2212735325616;3070.431970085519;3148.7481001520055;3167.4615315396436;3251.9349613007607;3263.200515873626;4265.850695225652;4541.5231400703115;4543.659225558096;4557.111363284161;4560.92818187729;4180.811863466151;4182.53992060873;4182.644475219579;4194.161167766459;4199.706020578561;4940.703557786508;4942.492539775367;4943.233798761162;4949.8020079196785;4981.676191047571;3306.2395649000623;3313.7349012078976;3552.769070010441;3557.8221088945447;3588.6411358864166;4844.861457557471;5007.84976450707;5008.398992120932;5079.187330027178;5107.8212262571005;3605.4952306677087;3609.7907201087405;3615.5480564929385;3616.8734094306574;3620.1311685195365;3667.4132138857653;3671.5777685565968;3673;3690.9815450922574;3694.113412780913;4058.350663893763;4167.395727619478;4200.1179752252365;4218.894527636834;4222.619964203236;4948.554630083309;4950.579200767975;4956.281339205795;4957.628177886673;4960.677555958851;1247.8769107802868;1259.5465632372338;1264.4815625593462;1266.6326765238161;1274.6749578857518;790.1505881177757;813.5240884082759;821.4496287487398;831.6216121664628;834.2700137379793;3822.9044273893373;3833.865814696472;3835.712321575893;3837.4819399380726;3845.8461661533224;4430.689851710414;4472.552744725502;4496.4154659894;4517.254652982287;4520.457545094544;3893.0656642406184;3894.7468414552964;3895.435161901565;3901.356276214393;3913.178232571135;2307.457747429353;2358.2807243790808;2392.892319845611;2399.832011759195;2420.2376251480728;4171.791410429466;4200.91598168041;4210.242156411814;4267.103908179255;4268.679849011274;4479.70881892676;4510.233260345749;4515;4533.32000199966;4588.348194649923;4198.223328684203;4242.618164365222;4244.511881133709;4449.176902273154;4840.322354870392;5086.745662716776;5145.639805213599;5150.639455238097;5159.845204643867;5164.535191832753;5068.898622027613;5086.567641750493;5100.770465329143;5141.742912854267;5165.457626949116;4493.550644935481;4554.772261386984;4798.4001999750035;4900.26496025599;4903.656744027884;3641.2899484601307;3652.433872749728;3659.359612067931;3659.7072234220664;3666.844943842681;5190.273361729436;5195.636305458582;5205.193195054772;5215.869603259949;5217.4260831307765;4851.975740121418;4932.4327702314695;4934.136526107988;5044.772985215747;5071.315372424676;4948.777305021211;4950.381202349707;4951.603871690329;4957.256411538154;4968.155413579628;4895.853124406273;4908.656394052382;4915.238138088602;4951.885825689335;4990.80036798524;4874.512548745097;5006.974965125297;5017.724025178651;5023;5046.34397528319;4814.518548145157;4841.515848415131;4848.466668666514;4920.975395123001;4930.260460930889 01:03:49 INFO - PROCESS | 3407 | |5;yui.html;625.7309356976501;671.6339594920768;683.7914436096988;723.1250187273399;729.1942403643975;12233.755324893496;12660;12681.809772853414;12684.746305073893;12687.365631718412;12919.418626161822;13020.804687929689;13025.934870325635;13040.608781736539;13052.412641431134;609.7347653770613;628.2272804450529;746.1233051164887;757.4357103957559;766.1342682768463;776.2586729673177;778.4473024152858;778.4550814429894;779.0223269796397;779.2908453307491;807.2411932783194;810.440795850864;810.8864758933754;810.9553974531399;811.0348685064754;638.6232123047402;656.8325077105308;772.7256823827572;794.31291932478;796.1998191817187;592.6207227374441;592.6592209479523;593.0984902947496;593.2880543347957;594.0169020271458;12832.807507887295;12905.483780648763;12933.094683372168;12973.675658108528;12991.090623656348;12182.81725774114;12433.93783031088;12468.937655311756;12680.809787853097;12968.027397945192;11347.262427942158;11376.431178441046;11452.541898324058;11691;11743.588974385855;3903.746256493319;3941.6649584785328;4019.718619696622;4181.080162364275;4181.226473102486;63451.68274158646;65016.67491662512;65927.67036164836;67122.3287767121;67297.66351168262;10042.748931276776;10246.231532635082;10286.742831429274;10417.9479102604;10520.210984176218;17013.14934253307;17045.14774261282;17140.485785426456;17184.742228866577;17199.1400429978;6570.310117437696;6570.572912760751;6634.834129146713;6647.966760166169;6847.897281540781;3568.946465803015;3619.4570814378053;3730.8880733578035;3748.362778327636;3812.466254724285;2749.5188342040065;2803.046964032239;2841.3606938438684;2849;2850.6721727001245;440.7245471580262;446.75875027484796;453.31593131516576;467.71469403663735;468.1199345230944;424.91501699660193;456.7647661454354;475.2324995132836;482.20676986357245;490.1031113063086;6714.160729908762;7041.155061392666;7327.45044121693;7414.777556673308;7460.589667568227;445.31866244645744;452.3170013279971;460.5555638808499;502.97436708235546;503.2677454303979;3315.270640459095;3359.1266270769775;3365.6129545102135;3402.625711171769;3541.610422853484;66525;102943;103715;107057;107700.46149769358;52438;54084.91830163375;54602.72698636561;55633.44366556364;58460.41539584635;43823.34264986028;53599;75519.9999999989;76025;76300.61849690716;6487.124238227968;6687.164104486939;6833.760818371333;6976.965115174392;7088.858222835617;46245;46967.29549056698;47346.526534734214;47651;49074.509254906996;59707.70146149241;59710.40289597222;62110;68294.31705682879;69701;10502.579896804042;11051.668449946514;11067.169962253025;11135;11331.490082946122;11675.824862626898;11864.584739534075;12065;12329.568465103679;12562.43469043877;10444.791104177722;10975.945120274348;11561;11592.710182245512;12279.631611051684;11146.721331966766;11862.762744745229;12107.636770896886;12414.317212553397;13012.8048079277;11374.146938979775;11485.712857178303;12068.215565988183;12075.033997280372;12378;9135.58889849945;10205.183585313305;10276.434796086287;10385.169186464913;10915.34507929495;10047.698569042941;10136.594536218468;10151;10332.225083118647;10610.204234682275 01:03:49 INFO - PROCESS | 3407 | __end_tp_report 01:03:49 INFO - PROCESS | 3407 | __start_cc_report 01:03:49 INFO - PROCESS | 3407 | _x_x_mozilla_cycle_collect,906 01:03:49 INFO - PROCESS | 3407 | __end_cc_report 01:03:49 INFO - PROCESS | 3407 | __startTimestamp1474185829269__endTimestamp 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | ------- Summary: start ------- 01:03:49 INFO - PROCESS | 3407 | Number of tests: 6 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#0] dojo.html Cycles:165 Average:4636.08 Median:4775.40 stddev:488.83 (10.2%) stddev-sans-first:462.31 01:03:49 INFO - PROCESS | 3407 | Values: 2556.6 2570.9 2601.8 2619.3 2622.4 4502.7 4598.1 4694.7 4713.5 4731.7 4605.3 4626.0 4630.5 4661.4 4663.7 4226.1 4282.7 4287.7 4338.5 4369.2 4348.5 4365.0 4368.0 4406.5 4463.5 4354.2 4378.1 4385.8 4496.8 4497.1 4382.1 4399.1 4407.7 4447.8 4464.8 4179.6 4210.1 4224.1 4232.0 4314.5 4657.9 4660.0 4749.6 4754.3 4758.2 4935.2 4976.4 5021.2 5024.0 5089.9 5048.9 5051.5 5061.1 5063.3 5119.2 4562.8 4613.8 4656.6 4691.8 4709.4 5096.7 5126.5 5136.4 5138.4 5201.2 4730.2 4932.0 4936.6 4940.4 4940.8 4847.6 4958.0 5018.1 5029.7 5074.4 4991.8 5001.8 5031.4 5036.0 5061.5 5008.1 5008.3 5013.5 5021.5 5084.2 4092.4 4126.0 4127.1 4172.1 4236.6 3717.5 3720.2 3757.6 3766.0 3785.6 4265.4 4269.0 4270.3 4311.4 4325.3 4842.0 4906.3 4910.1 5077.7 5082.8 4210.0 4257.6 4268.7 4322.7 4325.4 4538.7 4542.7 4546.1 4549.7 4604.7 4722.3 4763.1 4773.6 4775.4 4828.9 4930.5 4999.1 5033.6 5085.8 5093.4 4795.9 4797.1 4801.5 4841.6 4859.5 4979.8 4993.9 4996.2 4997.1 5056.0 4848.0 4906.0 4941.2 4976.7 5084.0 4836.2 4837.8 4841.7 4901.6 4907.2 4852.3 4854.5 4854.8 4858.1 4863.8 4674.9 4735.0 4766.0 4784.2 4840.6 4908.1 4918.8 4931.2 4987.5 4988.6 4807.9 4816.2 4820.8 4879.0 4881.0 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#1] ext.html Cycles:180 Average:44353.77 Median:19439.46 stddev:78514.50 (403.9%) stddev-sans-first:78665.92 01:03:49 INFO - PROCESS | 3407 | Values: 564.8 565.1 581.3 587.3 589.7 28148.7 28911.7 30992.7 31172.4 38369.4 40793.2 41467.4 41615.0 41622.8 41632.6 7621.7 7753.8 7759.4 7767.5 7769.7 7656.8 7745.6 7749.4 7753.6 7754.7 7796.3 7828.7 7845.0 7845.7 7846.3 7756.8 7781.7 7796.4 7797.6 7800.5 7540.1 7559.8 7603.6 7759.3 7767.7 37348.3 37967.8 38048.8 38084.8 38104.4 2777.8 2960.7 3156.9 3177.7 3500.9 28510.6 28615.3 29086.7 29159.4 30506.1 3470.2 3493.3 3544.5 3549.9 3588.7 245497.0 246373.0 246758.0 247054.0 247265.8 52386.7 52740.7 52768.5 52775.7 52802.7 32938.3 33998.2 34005.7 35598.5 54678.7 14991.6 15112.4 15149.4 15159.5 15162.5 11496.1 11556.9 11570.9 11603.5 11632.2 17476.3 17567.8 17642.4 17718.4 17785.1 6855.9 6871.3 6879.8 6974.6 7061.6 785.6 888.6 932.3 942.5 986.1 26539.6 28000.7 28202.2 28246.0 28467.6 751.3 938.9 988.8 993.1 1000.9 13522.3 14429.6 14613.9 14817.7 14869.3 379526.0 382419.0 383761.0 384425.0 386657.0 48814.8 49488.8 49532.8 49562.5 49612.0 1084.3 1117.9 1137.2 1187.0 1213.5 26537.3 29407.3 29435.3 29951.9 29955.6 41550.0 41697.6 42010.8 42097.2 42114.7 242393.0 243555.0 244955.0 244978.0 245417.0 24416.9 24544.1 25146.6 25387.4 26410.6 40585.0 40591.0 40869.8 41533.8 41679.0 34312.5 34428.7 34779.7 35123.8 35126.0 34994.3 35112.8 35177.0 35553.3 35786.0 34873.0 35108.1 35334.5 35746.5 35815.1 30015.7 32291.7 32378.0 32529.8 32636.7 24713.0 25001.0 25025.0 25110.1 25197.5 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#2] jquery.html Cycles:180 Average:24494.44 Median:51432.73 stddev:61472.38 (119.5%) stddev-sans-first:61618.09 01:03:49 INFO - PROCESS | 3407 | Values: 348.7 363.9 367.1 372.4 391.0 12312.1 13237.6 14074.2 16425.8 17125.8 15465.4 15942.5 16142.2 16607.6 17012.5 4501.4 4788.7 4817.0 4821.3 4827.1 3456.4 4745.9 4822.4 4823.4 4826.3 3665.4 5320.5 5560.1 5582.3 5681.1 5438.3 5584.5 5649.6 5651.7 5658.8 5446.7 5450.3 5453.1 5458.1 5458.9 12579.4 13192.6 20412.8 20574.6 20604.7 1870.9 1896.7 2026.5 2033.6 2368.5 8995.6 9243.8 9277.1 9626.8 9739.1 1665.2 1761.6 1782.8 1793.2 1811.9 41291.0 75573.0 75723.0 77146.2 84626.6 16175.8 16367.4 16497.6 16558.8 17105.3 29377.9 29439.6 29590.9 29635.6 29663.1 8720.1 8802.4 8827.8 8838.8 9100.2 7018.9 7162.7 7212.9 7245.0 7494.7 8035.3 8098.9 8358.5 8554.6 8582.3 3186.5 3208.9 3253.2 3270.7 3293.6 847.5 860.8 874.2 885.8 897.3 16024.2 16514.3 16655.9 16782.5 17568.6 835.8 851.6 854.0 867.9 927.1 9492.5 9583.5 9598.4 9702.7 9781.7 363430.0 364094.0 376193.0 376254.0 376338.0 74254.6 75850.6 75877.6 76484.6 77206.0 876.1 893.3 907.7 910.7 915.4 14764.3 14888.7 15050.5 15940.0 16551.3 39543.8 40123.0 40169.2 40239.8 40502.2 50744.0 52121.5 52807.0 53022.5 53700.5 7478.8 7767.8 7829.9 7925.2 8385.6 9686.7 10840.5 11010.7 11364.9 11730.6 9935.0 10179.0 10227.9 10278.4 10493.8 9224.0 9479.4 9613.3 9685.1 9825.7 9764.2 9999.2 10115.1 10164.6 10329.9 6822.8 7001.5 7070.5 7388.2 7528.0 8451.0 9914.9 9945.6 10230.2 10277.3 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#3] mootools.html Cycles:180 Average:4984.91 Median:4512.20 stddev:2894.17 (64.1%) stddev-sans-first:2888.61 01:03:49 INFO - PROCESS | 3407 | Values: 1240.8 1373.4 1394.0 1477.4 1680.6 6615.4 7506.0 7716.6 7751.3 7926.8 7790.7 7836.3 7838.7 7846.9 7862.9 2112.2 2123.9 2134.5 2137.1 2139.0 2156.2 2192.3 2215.7 2219.4 2220.4 2132.2 2132.5 2134.9 2137.5 2197.5 2132.3 2135.4 2136.1 2139.2 2140.3 2050.1 2094.5 2100.5 2100.7 2107.4 7781.3 7826.3 7846.3 7922.8 7939.0 4194.5 4354.2 4355.3 4367.4 4393.0 2692.9 2831.8 2882.1 2884.3 2888.4 961.5 963.4 980.6 981.5 987.1 10702.1 10755.4 10783.8 10787.6 10795.8 4007.8 4015.9 4169.9 4181.4 4210.1 8382.2 8438.9 8447.5 8448.1 8453.2 4468.0 4470.7 4473.7 4474.1 4475.2 3540.6 3679.3 4069.1 4151.1 4155.3 3319.6 3333.9 3506.7 3514.5 3515.0 1056.6 1062.2 1067.8 1071.6 1088.5 708.7 709.2 721.2 726.0 768.5 4457.2 4509.8 4605.3 4682.7 4694.7 416.2 418.0 418.3 422.5 424.9 3063.8 3118.7 3123.2 3156.1 3167.4 9257.0 9608.9 9662.1 9693.9 9711.6 9549.6 9603.4 9611.7 9644.9 9706.2 7201.2 7289.3 7302.7 7316.4 7318.0 4472.7 4512.8 4552.7 4577.5 4648.3 8702.4 8716.4 8741.3 8752.6 8847.6 8901.3 8960.5 9052.3 9109.9 9134.6 5469.4 5642.5 6100.5 6197.6 6204.4 7044.2 7278.1 7281.9 7348.3 7502.4 6707.3 6838.2 6967.4 7016.6 7161.6 6928.2 6936.0 7002.8 7160.2 7274.0 5899.1 5938.6 5965.2 6003.0 6006.6 4341.9 4377.5 4511.6 4575.2 4595.3 4333.0 4347.8 4433.5 4443.9 4492.3 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#4] prototype.html Cycles:180 Average:4025.38 Median:4460.86 stddev:1179.99 (26.5%) stddev-sans-first:1149.60 01:03:49 INFO - PROCESS | 3407 | Values: 294.8 296.0 297.9 298.9 304.9 4740.7 4808.1 4811.8 4822.8 4841.9 4736.8 4746.6 4763.6 4792.5 4796.5 3763.7 3774.7 3792.5 3803.8 3809.6 3498.9 3803.2 3810.6 3812.3 3822.9 3680.5 3717.2 3717.9 3723.2 3733.6 3682.9 3684.7 3697.4 3698.4 3708.2 3070.4 3148.7 3167.5 3251.9 3263.2 4265.9 4541.5 4543.7 4557.1 4560.9 4180.8 4182.5 4182.6 4194.2 4199.7 4940.7 4942.5 4943.2 4949.8 4981.7 3306.2 3313.7 3552.8 3557.8 3588.6 4844.9 5007.8 5008.4 5079.2 5107.8 3605.5 3609.8 3615.5 3616.9 3620.1 3667.4 3671.6 3673.0 3691.0 3694.1 4058.4 4167.4 4200.1 4218.9 4222.6 4948.6 4950.6 4956.3 4957.6 4960.7 1247.9 1259.5 1264.5 1266.6 1274.7 790.2 813.5 821.4 831.6 834.3 3822.9 3833.9 3835.7 3837.5 3845.8 4430.7 4472.6 4496.4 4517.3 4520.5 3893.1 3894.7 3895.4 3901.4 3913.2 2307.5 2358.3 2392.9 2399.8 2420.2 4171.8 4200.9 4210.2 4267.1 4268.7 4479.7 4510.2 4515.0 4533.3 4588.3 4198.2 4242.6 4244.5 4449.2 4840.3 5086.7 5145.6 5150.6 5159.8 5164.5 5068.9 5086.6 5100.8 5141.7 5165.5 4493.6 4554.8 4798.4 4900.3 4903.7 3641.3 3652.4 3659.4 3659.7 3666.8 5190.3 5195.6 5205.2 5215.9 5217.4 4852.0 4932.4 4934.1 5044.8 5071.3 4948.8 4950.4 4951.6 4957.3 4968.2 4895.9 4908.7 4915.2 4951.9 4990.8 4874.5 5007.0 5017.7 5023.0 5046.3 4814.5 4841.5 4848.5 4921.0 4930.3 01:03:49 INFO - PROCESS | 3407 | 01:03:49 INFO - PROCESS | 3407 | [#5] yui.html Cycles:180 Average:16779.24 Median:4100.40 stddev:23661.51 (577.1%) stddev-sans-first:23696.80 01:03:49 INFO - PROCESS | 3407 | Values: 625.7 671.6 683.8 723.1 729.2 12233.8 12660.0 12681.8 12684.7 12687.4 12919.4 13020.8 13025.9 13040.6 13052.4 609.7 628.2 746.1 757.4 766.1 776.3 778.4 778.5 779.0 779.3 807.2 810.4 810.9 811.0 811.0 638.6 656.8 772.7 794.3 796.2 592.6 592.7 593.1 593.3 594.0 12832.8 12905.5 12933.1 12973.7 12991.1 12182.8 12433.9 12468.9 12680.8 12968.0 11347.3 11376.4 11452.5 11691.0 11743.6 3903.7 3941.7 4019.7 4181.1 4181.2 63451.7 65016.7 65927.7 67122.3 67297.7 10042.7 10246.2 10286.7 10417.9 10520.2 17013.1 17045.1 17140.5 17184.7 17199.1 6570.3 6570.6 6634.8 6648.0 6847.9 3568.9 3619.5 3730.9 3748.4 3812.5 2749.5 2803.0 2841.4 2849.0 2850.7 440.7 446.8 453.3 467.7 468.1 424.9 456.8 475.2 482.2 490.1 6714.2 7041.2 7327.5 7414.8 7460.6 445.3 452.3 460.6 503.0 503.3 3315.3 3359.1 3365.6 3402.6 3541.6 66525.0 102943.0 103715.0 107057.0 107700.5 52438.0 54084.9 54602.7 55633.4 58460.4 43823.3 53599.0 75520.0 76025.0 76300.6 6487.1 6687.2 6833.8 6977.0 7088.9 46245.0 46967.3 47346.5 47651.0 49074.5 59707.7 59710.4 62110.0 68294.3 69701.0 10502.6 11051.7 11067.2 11135.0 11331.5 11675.8 11864.6 12065.0 12329.6 12562.4 10444.8 10975.9 11561.0 11592.7 12279.6 11146.7 11862.8 12107.6 12414.3 13012.8 11374.1 11485.7 12068.2 12075.0 12378.0 9135.6 10205.2 10276.4 10385.2 10915.3 10047.7 10136.6 10151.0 10332.2 10610.2 01:03:49 INFO - PROCESS | 3407 | -------- Summary: end -------- 01:03:49 INFO - PROCESS | 3407 | 01:03:50 INFO - TEST-INFO | 3407: exit 0 01:03:50 INFO - TEST-OK | dromaeo_css | took 1122568ms 01:03:50 INFO - TEST-START | kraken 01:03:50 INFO - operating with platform_type : linux_ 01:03:50 INFO - Initialising browser for kraken test... 01:03:50 INFO - TEST-INFO | started process 3711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp87Nyc5/profile http://localhost:47514/getInfo.html) 01:03:54 INFO - PROCESS | 3711 | __metrics Screen width/height:1600/1200 01:03:54 INFO - PROCESS | 3711 | colorDepth:24 01:03:54 INFO - PROCESS | 3711 | Browser inner width/height: 1024/697 01:03:54 INFO - PROCESS | 3711 | __metrics 01:03:55 INFO - PROCESS | 3711 | 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" 01:03:55 INFO - PROCESS | 3711 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:03:55 INFO - PROCESS | 3711 | console.error: PushService: 01:03:55 INFO - PROCESS | 3711 | stateChangeProcessEnqueue: Error transitioning state 01:03:55 INFO - PROCESS | 3711 | UnknownError 01:03:56 INFO - TEST-INFO | 3711: exit 0 01:03:56 INFO - Browser initialized. 01:03:56 INFO - Running cycle 1/1 for kraken test... 01:03:56 INFO - TEST-INFO | started process 3791 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp87Nyc5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:04:08 INFO - PROCESS | 3791 | RSS: Main: 174862336 01:04:08 INFO - PROCESS | 3791 | 01:04:11 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47514/tests/kraken/driver.html?testName=audio-beat-detection) 01:04:12 INFO - PROCESS | 3791 | RSS: Main: 242860032 01:04:12 INFO - PROCESS | 3791 | 01:04:15 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47514/tests/kraken/driver.html?testName=audio-dft) 01:04:15 INFO - PROCESS | 3791 | RSS: Main: 218177536 01:04:15 INFO - PROCESS | 3791 | 01:04:19 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47514/tests/kraken/driver.html?testName=audio-fft) 01:04:19 INFO - PROCESS | 3791 | RSS: Main: 300269568 01:04:19 INFO - PROCESS | 3791 | 01:04:21 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47514/tests/kraken/driver.html?testName=audio-oscillator) 01:04:22 INFO - PROCESS | 3791 | RSS: Main: 210092032 01:04:22 INFO - PROCESS | 3791 | 01:04:24 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47514/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:04:25 INFO - PROCESS | 3791 | RSS: Main: 213831680 01:04:25 INFO - PROCESS | 3791 | 01:04:28 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47514/tests/kraken/driver.html?testName=imaging-darkroom) 01:04:29 INFO - PROCESS | 3791 | RSS: Main: 250515456 01:04:29 INFO - PROCESS | 3791 | 01:04:32 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47514/tests/kraken/driver.html?testName=imaging-desaturate) 01:04:33 INFO - PROCESS | 3791 | RSS: Main: 244572160 01:04:33 INFO - PROCESS | 3791 | 01:04:36 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47514/tests/kraken/driver.html?testName=json-parse-financial) 01:04:36 INFO - PROCESS | 3791 | RSS: Main: 247435264 01:04:36 INFO - PROCESS | 3791 | 01:04:38 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47514/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:04:39 INFO - PROCESS | 3791 | RSS: Main: 210997248 01:04:39 INFO - PROCESS | 3791 | 01:04:41 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:04:41 INFO - PROCESS | 3791 | RSS: Main: 218959872 01:04:41 INFO - PROCESS | 3791 | 01:04:43 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:04:44 INFO - PROCESS | 3791 | RSS: Main: 222380032 01:04:44 INFO - PROCESS | 3791 | 01:04:47 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:04:47 INFO - PROCESS | 3791 | RSS: Main: 224079872 01:04:47 INFO - PROCESS | 3791 | 01:04:50 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:04:51 INFO - PROCESS | 3791 | RSS: Main: 221106176 01:04:51 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | Cycle 1(1): loaded http://localhost:47514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47514/tests/kraken/driver.html?testName=ai-astar) 01:04:53 INFO - PROCESS | 3791 | RSS: Main: 272826368 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | __start_tp_report 01:04:53 INFO - PROCESS | 3791 | _x_x_mozilla_page_load 01:04:53 INFO - PROCESS | 3791 | _x_x_mozilla_page_load_details 01:04:53 INFO - PROCESS | 3791 | |i|pagename|runs| 01:04:53 INFO - PROCESS | 3791 | |0;ai-astar;115;116;117;117;115;117;115;117;115;116 01:04:53 INFO - PROCESS | 3791 | |1;audio-beat-detection;162;122;121;159;125;122;128;127;151;126 01:04:53 INFO - PROCESS | 3791 | |2;audio-dft;161;161;161;160;161;161;161;161;161;162 01:04:53 INFO - PROCESS | 3791 | |3;audio-fft;76;76;77;116;76;79;79;77;123;78 01:04:53 INFO - PROCESS | 3791 | |4;audio-oscillator;83;84;117;84;85;84;111;83;83;119 01:04:53 INFO - PROCESS | 3791 | |5;imaging-gaussian-blur;117;118;117;118;118;118;118;117;117;117 01:04:53 INFO - PROCESS | 3791 | |6;imaging-darkroom;147;146;146;147;146;147;146;146;146;146 01:04:53 INFO - PROCESS | 3791 | |7;imaging-desaturate;95;95;95;96;95;96;95;95;95;96 01:04:53 INFO - PROCESS | 3791 | |8;json-parse-financial;64;64;64;65;64;64;65;64;64;65 01:04:53 INFO - PROCESS | 3791 | |9;json-stringify-tinderbox;66;67;66;66;66;65;67;66;66;66 01:04:53 INFO - PROCESS | 3791 | |10;stanford-crypto-aes;86;88;83;83;82;82;82;82;81;82 01:04:53 INFO - PROCESS | 3791 | |11;stanford-crypto-ccm;134;133;141;134;139;131;132;131;171;133 01:04:53 INFO - PROCESS | 3791 | |12;stanford-crypto-pbkdf2;181;174;173;175;177;174;175;181;174;174 01:04:53 INFO - PROCESS | 3791 | |13;stanford-crypto-sha256-iterative;70;70;71;74;68;69;74;70;70;69 01:04:53 INFO - PROCESS | 3791 | __end_tp_report 01:04:53 INFO - PROCESS | 3791 | __start_cc_report 01:04:53 INFO - PROCESS | 3791 | _x_x_mozilla_cycle_collect,1930 01:04:53 INFO - PROCESS | 3791 | __end_cc_report 01:04:53 INFO - PROCESS | 3791 | __startTimestamp1474185893633__endTimestamp 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | ------- Summary: start ------- 01:04:53 INFO - PROCESS | 3791 | Number of tests: 14 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.50 stddev:0.94 (0.8%) stddev-sans-first:0.93 01:04:53 INFO - PROCESS | 3791 | Values: 115.0 116.0 117.0 117.0 115.0 117.0 115.0 117.0 115.0 116.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:127.50 stddev:16.28 (12.8%) stddev-sans-first:13.84 01:04:53 INFO - PROCESS | 3791 | Values: 162.0 122.0 121.0 159.0 125.0 122.0 128.0 127.0 151.0 126.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 01:04:53 INFO - PROCESS | 3791 | Values: 161.0 161.0 161.0 160.0 161.0 161.0 161.0 161.0 161.0 162.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#3] audio-fft Cycles:10 Average:85.70 Median:77.00 stddev:17.93 (23.3%) stddev-sans-first:18.67 01:04:53 INFO - PROCESS | 3791 | Values: 76.0 76.0 77.0 116.0 76.0 79.0 79.0 77.0 123.0 78.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:83.50 stddev:15.57 (18.6%) stddev-sans-first:16.06 01:04:53 INFO - PROCESS | 3791 | Values: 83.0 84.0 117.0 84.0 85.0 84.0 111.0 83.0 83.0 119.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 01:04:53 INFO - PROCESS | 3791 | Values: 117.0 118.0 117.0 118.0 118.0 118.0 118.0 117.0 117.0 117.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 01:04:53 INFO - PROCESS | 3791 | Values: 147.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 01:04:53 INFO - PROCESS | 3791 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 01:04:53 INFO - PROCESS | 3791 | Values: 64.0 64.0 64.0 65.0 64.0 64.0 65.0 64.0 64.0 65.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 01:04:53 INFO - PROCESS | 3791 | Values: 66.0 67.0 66.0 66.0 66.0 65.0 67.0 66.0 66.0 66.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:82.50 stddev:2.18 (2.6%) stddev-sans-first:2.05 01:04:53 INFO - PROCESS | 3791 | Values: 86.0 88.0 83.0 83.0 82.0 82.0 82.0 82.0 81.0 82.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:134.00 stddev:12.09 (9.0%) stddev-sans-first:12.74 01:04:53 INFO - PROCESS | 3791 | Values: 134.0 133.0 141.0 134.0 139.0 131.0 132.0 131.0 171.0 133.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:175.00 stddev:2.94 (1.7%) stddev-sans-first:2.44 01:04:53 INFO - PROCESS | 3791 | Values: 181.0 174.0 173.0 175.0 177.0 174.0 175.0 181.0 174.0 174.0 01:04:53 INFO - PROCESS | 3791 | 01:04:53 INFO - PROCESS | 3791 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.00 stddev:2.01 (2.9%) stddev-sans-first:2.13 01:04:53 INFO - PROCESS | 3791 | Values: 70.0 70.0 71.0 74.0 68.0 69.0 74.0 70.0 70.0 69.0 01:04:53 INFO - PROCESS | 3791 | -------- Summary: end -------- 01:04:53 INFO - PROCESS | 3791 | 01:04:54 INFO - TEST-INFO | 3791: exit 0 01:04:54 INFO - TEST-OK | kraken | took 64735ms 01:04:54 INFO - SUITE-END | took 1187s 01:04:55 INFO - Completed test suite (00:19:47) 01:04:55 INFO - javascript benchmark 01:04:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2556.5653838847393, 2570.871456427178, 2601.7918566514677, 2619.2927909464447, 2622.436176222112, 4502.707324023936, 4598.103369842882, 4694.718316900984, 4713.528647135285, 4731.739754313516, 4605.332226827117, 4625.976870115653, 4630.490646028934, 4661.37071495348, 4663.720176789395, 4226.133642603267, 4282.74303541787, 4287.656987440997, 4338.544452832454, 4369.169857727037, 4348.4999225089205, 4365.039691267916, 4367.978160109195, 4406.515283318832, 4463.486699029604, 4354.216241076605, 4378.102488989765, 4385.8464953726625, 4496.842610508633, 4497.078098989716, 4382.0797632497215, 4399.054203346264, 4407.713498622579, 4447.755373454443, 4464.754438505881, 4179.561146079679, 4210.073783767566, 4224.134052519226, 4232.0, 4314.503832059322, 4657.906841863144, 4660.021395506948, 4749.62003039756, 4754.263089221176, 4758.214894542396, 4935.210366341368, 4976.377952755905, 5021.221710634857, 5023.974880125613, 5089.949100509021, 5048.873778155576, 5051.545360917535, 5061.063703214881, 5063.26582645518, 5119.2321151827155, 4562.817487300537, 4613.792379342937, 4656.627469802475, 4691.835785747482, 4709.434867815884, 5096.719680417613, 5126.487351264918, 5136.357955255593, 5138.3577052868395, 5201.193814958688, 4730.219513780256, 4931.950680493221, 4936.555709986047, 4940.382452193476, 4940.827071052568, 4847.587955023862, 4957.950420495749, 5018.1218286800395, 5029.748512574357, 5074.3657042869645, 4991.80032798684, 5001.799928002912, 5031.371078615173, 5036.043151801146, 5061.468545802712, 5008.12357837377, 5008.323876276729, 5013.523715247045, 5021.548060674557, 5084.186530155157, 4092.3656833190307, 4125.958740412558, 4127.133302006612, 4172.08214192877, 4236.597523235288, 3717.498137751423, 3720.1815600567834, 3757.5678796938155, 3766.0396598867114, 3785.640364165455, 4265.381519679691, 4269.0, 4270.316749320156, 4311.353297005411, 4325.307950727932, 4841.95158048415, 4906.2640603909695, 4910.140725373046, 5077.695338279715, 5082.822101226439, 4209.97895010523, 4257.57424257578, 4268.658507319407, 4322.69741118125, 4325.437693099877, 4538.704984176018, 4542.682012259176, 4546.1135078659345, 4549.7042692224895, 4604.654650901129, 4722.268048452495, 4763.118823017753, 4773.570378665937, 4775.403074615673, 4828.855134345975, 4930.457649658463, 4999.125153098266, 5033.597312215087, 5085.847424577269, 5093.363329583802, 4795.856124316276, 4797.112534181188, 4801.5438533339275, 4841.588464980376, 4859.538343857328, 4979.775910083982, 4993.925091123563, 4996.15065438869, 4997.100521906092, 5056.039352523009, 4847.981923795901, 4905.950940490692, 4941.209406495087, 4976.651634385558, 5084.0, 4836.177849765619, 4837.7581120944515, 4841.733704646278, 4901.5588597026435, 4907.165781817171, 4852.296417019442, 4854.490278520704, 4854.83008094715, 4858.076965376567, 4863.781129849236, 4674.906501870011, 4735.0, 4766.022965292037, 4784.186688262934, 4840.6127509799835, 4908.092002979461, 4918.803247870045, 4931.211006239128, 4987.451380348231, 4988.575971042357, 4807.879803004953, 4816.229403295456, 4820.807167713252, 4879.04858552593, 4881.048195601824], "value": 4604.525323695123, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.7515093358921, 565.0789213581863, 581.3111462916441, 587.3040447070222, 589.6933594530844, 28148.718512814867, 28911.710882891166, 30992.690073099264, 31172.376552468937, 38369.424458633075, 40793.18413631726, 41467.37798933018, 41615.0, 41622.791886040526, 41632.58367416325, 7621.695132194719, 7753.767386978399, 7759.379249660042, 7767.456278060538, 7769.689212431496, 7656.808579785495, 7745.6127193640095, 7749.418793590475, 7753.573553454557, 7754.689812407497, 7796.337311328544, 7828.725994590191, 7844.960775196116, 7845.725399611015, 7846.254605812439, 7756.767296981071, 7781.688732450695, 7796.376289896823, 7797.57113358765, 7800.492967957065, 7540.095188577406, 7559.773206803805, 7603.581803000833, 7759.301662850314, 7767.72812951544, 37348.253034939145, 37967.81016094902, 38048.80975595104, 38084.80957595222, 38104.42843357352, 2777.7638900693264, 2960.7335339819306, 3156.875884148598, 3177.682231776827, 3500.8949731508096, 28510.572341414896, 28615.28461788451, 29086.70913290861, 29159.416811663647, 30506.08481745551, 3470.1845066409373, 3493.3013397320638, 3544.4506101554302, 3549.8757543485854, 3588.67701906827, 245497.0, 246373.0, 246758.0, 247054.0, 247265.7636711823, 52386.73806630942, 52740.73629631904, 52768.472315276354, 52775.73612131915, 52802.73598631982, 32938.341233175204, 33998.150046249044, 34005.659943400744, 35598.46602300968, 54678.72660636751, 14991.625209369853, 15112.395504179709, 15149.394024239127, 15159.545213643609, 15162.545123646309, 11496.080313574894, 11556.94221528887, 11570.94214528922, 11603.535858565563, 11632.18574699763, 17476.30094796194, 17567.82432175662, 17642.38251661186, 17718.379856704953, 17785.110744462727, 6855.897161542581, 6871.312868713088, 6879.793606191723, 6974.616396098263, 7061.646917654097, 785.6464590934103, 888.5734847273345, 932.3314249363851, 942.458086600194, 986.0878687214298, 26539.601905971427, 28000.71999280022, 28202.20562504338, 28246.0, 28467.57298640522, 751.2562563062529, 938.8920274168421, 988.8071825993862, 993.145894530703, 1000.8690180096445, 13522.32388380577, 14429.639259018608, 14613.92693036528, 14817.70364592702, 14869.330880110421, 379526.0, 382419.0, 383761.0, 384425.0, 386657.0, 48814.75592622014, 49488.752556237705, 49532.75233623809, 49562.50437495651, 49612.0, 1084.2572837606265, 1117.9049780768726, 1137.2096393006946, 1186.9940650296694, 1213.514594162342, 26537.33656658599, 29407.26481837971, 29435.264118397212, 29951.850240748656, 29955.550666739582, 41550.0, 41697.58302416937, 42010.78994605008, 42097.158056839304, 42114.683692395985, 242393.0, 243555.0, 244955.0, 244978.0, 245417.0, 24416.87791561031, 24544.140955066487, 25146.62280065764, 25387.36531586725, 26410.603840942786, 40585.0, 40591.0, 40869.79565102155, 41533.79233103815, 41679.0, 34312.485312720826, 34428.655713442546, 34779.65220347764, 35123.82438087793, 35126.0, 34994.300113998084, 35112.82443587766, 35177.0, 35553.288934220654, 35786.0, 34873.0, 35108.12229694278, 35334.46998295079, 35746.463803042454, 35815.10462238361, 30015.699843002163, 32291.677083229806, 32378.0, 32529.83735081404, 32636.673633263363, 24713.0, 25001.0, 25025.0, 25110.12114575981, 25197.496050079262], "value": 17042.60773321133, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.67573156964045, 363.88355726167634, 367.10609665464597, 372.43018182181265, 390.97564381320944, 12312.076594255434, 13237.602871913847, 14074.155550666967, 16425.83574164258, 17125.82874171258, 15465.381384744596, 15942.521724348288, 16142.192890355494, 16607.584810379747, 17012.48962531123, 4501.414816073906, 4788.664793464459, 4816.97591512042, 4821.325014497973, 4827.082854257697, 3456.360573293936, 4745.90508189836, 4822.445418776833, 4823.445303790057, 4826.3001864729595, 3665.3585622516084, 5320.467953204688, 5560.082586373263, 5582.302212223472, 5681.11942648888, 5438.320209973754, 5584.46947539983, 5649.604527683064, 5651.717414129298, 5658.8302350929125, 5446.700431476307, 5450.264214331054, 5453.100238460649, 5458.099413596752, 5458.890822183574, 12579.37103144839, 13192.604221873264, 20412.79587204124, 20574.588508229903, 20604.690929636068, 1870.8783929044705, 1896.6680830854546, 2026.4731169895786, 2033.6441122803599, 2368.4907744834773, 8995.640174393016, 9243.815123697488, 9277.118673725985, 9626.75933101671, 9739.123478886864, 1665.1757380096808, 1761.5508045448457, 1782.803891571926, 1793.219949322036, 1811.9275228990825, 41291.0, 75573.0, 75723.0, 77146.22853771503, 84626.57686711589, 16175.757363639555, 16367.42714005004, 16497.587560310847, 16558.751618725728, 17105.315787368614, 29377.85887469684, 29439.558406623917, 29590.852045739633, 29635.55546666802, 29663.110106696833, 8720.12798720123, 8802.383833131747, 8827.779305517284, 8838.779030524289, 9100.22648074921, 7018.9298107019295, 7162.713491460283, 7212.891806622905, 7245.0, 7494.737684181027, 8035.316998055114, 8098.878516822252, 8358.540280284642, 8554.61504232311, 8582.313414926792, 3186.5490002837023, 3208.903732888017, 3253.21001033793, 3270.73834093272, 3293.604767427924, 847.4830353484369, 860.7718954477068, 874.2437791310471, 885.8051228729798, 897.3494216692845, 16024.19879006045, 16514.2568584414, 16655.91672041632, 16782.496525104267, 17568.56078598045, 835.8286551256978, 851.5997481183833, 854.047736773493, 867.8568036274071, 927.0868194828089, 9492.525373731285, 9583.520823958914, 9598.376105553116, 9702.70891873245, 9781.74494095071, 363430.0, 364094.0, 376193.0, 376254.0, 376338.0, 74254.62872685603, 75850.62074689701, 75877.6206118966, 76484.61757691287, 77206.0, 876.0626130040795, 893.2764460786653, 907.7412937312835, 910.7113434490254, 915.4461550761827, 14764.33560489802, 14888.702225955636, 15050.548483545512, 15940.043597384194, 16551.337946482487, 39543.80228098841, 40122.984626921636, 40169.1966160681, 40239.79880100581, 40502.18995620012, 50743.985120296646, 52121.47878521318, 52807.0, 53022.46977530175, 53700.46299536955, 7478.813029674084, 7767.844643107219, 7829.882551761614, 7925.207479252029, 8385.580720963806, 9686.70939871805, 10840.457977100956, 11010.669679909615, 11364.94317528407, 11730.648080557597, 9934.950325248328, 10178.982101790058, 10227.897721022695, 10278.434686092336, 10493.790124197321, 9223.953880230556, 9479.383840050376, 9613.279004074582, 9685.079917407835, 9825.722656054668, 9764.170045546209, 9999.208297047133, 10115.140213081677, 10164.644237451654, 10329.896701032894, 6822.761203357859, 7001.474889383215, 7070.505064645426, 7388.187299397169, 7528.0, 8450.957745211235, 9914.900850991686, 9945.602175913173, 10230.181585473294, 10277.280590358603], "value": 8686.568832659794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1240.7766602011634, 1373.4368908747415, 1393.9930300348499, 1477.4090363854457, 1680.605057811414, 6615.371539703727, 7506.024216851809, 7716.575588342639, 7751.302382785549, 7926.841463170733, 7790.727324543628, 7836.333911617505, 7838.72564460244, 7846.882296765539, 7862.882056769153, 2112.186808078893, 2123.925662601809, 2134.466383404149, 2137.0703743871386, 2139.0267428320153, 2156.1698745982753, 2192.331338941622, 2215.667649852519, 2219.3563866478703, 2220.3893929169526, 2132.2004248406847, 2132.509522809747, 2134.8612340197915, 2137.487003119248, 2197.516546359798, 2132.3283165802723, 2135.39141344716, 2136.0601335412525, 2139.187108898624, 2140.3044010696585, 2050.0569737920573, 2094.476380904774, 2100.4958809885516, 2100.7479102507646, 2107.4099252209407, 7781.299683028555, 7826.295633393022, 7846.254605812439, 7922.841543169162, 7938.960305198437, 4194.454720886296, 4354.172707185625, 4355.3031514957775, 4367.432233809616, 4393.033532622818, 2692.9057482988196, 2831.7593004594632, 2882.0777351247607, 2884.322184286691, 2888.393437378153, 961.4519723757461, 963.3641796414333, 980.5734505490063, 981.5435822342645, 987.0573602209872, 10702.090322322612, 10755.354678719303, 10783.83824242629, 10787.5684972601, 10795.78408431827, 4007.7595344278852, 4015.9196816063513, 4169.874903752831, 4181.372794080851, 4210.136921930997, 8382.203690649378, 8438.915610843937, 8447.493150410872, 8448.07071222165, 8453.154684531497, 4467.977660111678, 4470.709403888737, 4473.664475164311, 4474.060447306102, 4475.239209334421, 3540.628234035389, 3679.3377192105677, 4069.064115253508, 4151.066010147692, 4155.314373128461, 3319.55186049885, 3333.916652083669, 3506.6855875122296, 3514.525539052195, 3514.9648503514636, 1056.6143357674393, 1062.1874266186373, 1067.8024565455416, 1071.5767271927546, 1088.5373716170595, 708.6846353372699, 709.1950636028146, 721.2318880392365, 725.989110163348, 768.4812751392788, 4457.2199865023495, 4509.819607215674, 4605.309203619417, 4682.672212945061, 4694.694844835075, 416.16351134220434, 417.9602937720912, 418.26803094584477, 422.47612959929467, 424.88528097413524, 3063.846807659608, 3118.703723146343, 3123.2191952012, 3156.0847354267007, 3167.3665266946705, 9257.0, 9608.90391096094, 9662.08210220028, 9693.903060969442, 9711.611535538499, 9549.570269337897, 9603.423794572209, 9611.663591774255, 9644.855327170098, 9706.174975127193, 7201.243869393743, 7289.307515785992, 7302.671379788028, 7316.414686825041, 7318.0, 4472.686911916265, 4512.819487220474, 4552.658550608784, 4577.542245775396, 4648.279516674921, 8702.390832641908, 8716.38985271025, 8741.300695944183, 8752.649894004424, 8847.601857916281, 8901.33240006989, 8960.462372257685, 9052.321075919464, 9109.90890091117, 9134.634614615341, 5469.398366179637, 5642.520385767091, 6100.481459075851, 6197.566170368031, 6204.410580994799, 7044.189918159273, 7278.09023872016, 7281.927180728125, 7348.265173482609, 7502.399808015237, 6707.29573394806, 6838.179418469816, 6967.407770339578, 7016.5790052597, 7161.57030578167, 6928.203256625553, 6936.0, 7002.7899163025195, 7160.176579693193, 7273.9636301818155, 5899.144624029406, 5938.554608404301, 5965.164876917322, 6003.039513677791, 6006.579539432198, 4341.913161736811, 4377.4528183977, 4511.571400716926, 4575.222212223864, 4595.310703394518, 4332.956670433256, 4347.804348804375, 4433.46798384196, 4443.933340999953, 4492.326151077234], "value": 3919.768102975884, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.75535305696275, 295.95116805727054, 297.92105092150575, 298.94021195760854, 304.8597645083262, 4740.700760131338, 4808.110499557584, 4811.831585894494, 4822.782974766135, 4841.854744357666, 4736.778990286603, 4746.62027037837, 4763.571278584926, 4792.496787837279, 4796.496367881375, 3763.6989040876733, 3774.698024158061, 3792.4880141181, 3803.8478460861534, 3809.638084381993, 3498.9300213995707, 3803.1633040731135, 3810.5617853946737, 3812.3328417526823, 3822.8661996830115, 3680.5215322008103, 3717.2008018276065, 3717.9365452174034, 3723.218124193909, 3733.5893051764283, 3682.8895133146048, 3684.6683798458, 3697.4084146536425, 3698.445233215026, 3708.2212735325616, 3070.431970085519, 3148.7481001520055, 3167.4615315396436, 3251.9349613007607, 3263.200515873626, 4265.850695225652, 4541.5231400703115, 4543.659225558096, 4557.111363284161, 4560.92818187729, 4180.811863466151, 4182.53992060873, 4182.644475219579, 4194.161167766459, 4199.706020578561, 4940.703557786508, 4942.492539775367, 4943.233798761162, 4949.8020079196785, 4981.676191047571, 3306.2395649000623, 3313.7349012078976, 3552.769070010441, 3557.8221088945447, 3588.6411358864166, 4844.861457557471, 5007.84976450707, 5008.398992120932, 5079.187330027178, 5107.8212262571005, 3605.4952306677087, 3609.7907201087405, 3615.5480564929385, 3616.8734094306574, 3620.1311685195365, 3667.4132138857653, 3671.5777685565968, 3673.0, 3690.9815450922574, 3694.113412780913, 4058.350663893763, 4167.395727619478, 4200.1179752252365, 4218.894527636834, 4222.619964203236, 4948.554630083309, 4950.579200767975, 4956.281339205795, 4957.628177886673, 4960.677555958851, 1247.8769107802868, 1259.5465632372338, 1264.4815625593462, 1266.6326765238161, 1274.6749578857518, 790.1505881177757, 813.5240884082759, 821.4496287487398, 831.6216121664628, 834.2700137379793, 3822.9044273893373, 3833.865814696472, 3835.712321575893, 3837.4819399380726, 3845.8461661533224, 4430.689851710414, 4472.552744725502, 4496.4154659894, 4517.254652982287, 4520.457545094544, 3893.0656642406184, 3894.7468414552964, 3895.435161901565, 3901.356276214393, 3913.178232571135, 2307.457747429353, 2358.2807243790808, 2392.892319845611, 2399.832011759195, 2420.2376251480728, 4171.791410429466, 4200.91598168041, 4210.242156411814, 4267.103908179255, 4268.679849011274, 4479.70881892676, 4510.233260345749, 4515.0, 4533.32000199966, 4588.348194649923, 4198.223328684203, 4242.618164365222, 4244.511881133709, 4449.176902273154, 4840.322354870392, 5086.745662716776, 5145.639805213599, 5150.639455238097, 5159.845204643867, 5164.535191832753, 5068.898622027613, 5086.567641750493, 5100.770465329143, 5141.742912854267, 5165.457626949116, 4493.550644935481, 4554.772261386984, 4798.4001999750035, 4900.26496025599, 4903.656744027884, 3641.2899484601307, 3652.433872749728, 3659.359612067931, 3659.7072234220664, 3666.844943842681, 5190.273361729436, 5195.636305458582, 5205.193195054772, 5215.869603259949, 5217.4260831307765, 4851.975740121418, 4932.4327702314695, 4934.136526107988, 5044.772985215747, 5071.315372424676, 4948.777305021211, 4950.381202349707, 4951.603871690329, 4957.256411538154, 4968.155413579628, 4895.853124406273, 4908.656394052382, 4915.238138088602, 4951.885825689335, 4990.80036798524, 4874.512548745097, 5006.974965125297, 5017.724025178651, 5023.0, 5046.34397528319, 4814.518548145157, 4841.515848415131, 4848.466668666514, 4920.975395123001, 4930.260460930889], "value": 3654.5567395462335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.7309356976501, 671.6339594920768, 683.7914436096988, 723.1250187273399, 729.1942403643975, 12233.755324893496, 12660.0, 12681.809772853414, 12684.746305073893, 12687.365631718412, 12919.418626161822, 13020.804687929689, 13025.934870325635, 13040.608781736539, 13052.412641431134, 609.7347653770613, 628.2272804450529, 746.1233051164887, 757.4357103957559, 766.1342682768463, 776.2586729673177, 778.4473024152858, 778.4550814429894, 779.0223269796397, 779.2908453307491, 807.2411932783194, 810.440795850864, 810.8864758933754, 810.9553974531399, 811.0348685064754, 638.6232123047402, 656.8325077105308, 772.7256823827572, 794.31291932478, 796.1998191817187, 592.6207227374441, 592.6592209479523, 593.0984902947496, 593.2880543347957, 594.0169020271458, 12832.807507887295, 12905.483780648763, 12933.094683372168, 12973.675658108528, 12991.090623656348, 12182.81725774114, 12433.93783031088, 12468.937655311756, 12680.809787853097, 12968.027397945192, 11347.262427942158, 11376.431178441046, 11452.541898324058, 11691.0, 11743.588974385855, 3903.746256493319, 3941.6649584785328, 4019.718619696622, 4181.080162364275, 4181.226473102486, 63451.68274158646, 65016.67491662512, 65927.67036164836, 67122.3287767121, 67297.66351168262, 10042.748931276776, 10246.231532635082, 10286.742831429274, 10417.9479102604, 10520.210984176218, 17013.14934253307, 17045.14774261282, 17140.485785426456, 17184.742228866577, 17199.1400429978, 6570.310117437696, 6570.572912760751, 6634.834129146713, 6647.966760166169, 6847.897281540781, 3568.946465803015, 3619.4570814378053, 3730.8880733578035, 3748.362778327636, 3812.466254724285, 2749.5188342040065, 2803.046964032239, 2841.3606938438684, 2849.0, 2850.6721727001245, 440.7245471580262, 446.75875027484796, 453.31593131516576, 467.71469403663735, 468.1199345230944, 424.91501699660193, 456.7647661454354, 475.2324995132836, 482.20676986357245, 490.1031113063086, 6714.160729908762, 7041.155061392666, 7327.45044121693, 7414.777556673308, 7460.589667568227, 445.31866244645744, 452.3170013279971, 460.5555638808499, 502.97436708235546, 503.2677454303979, 3315.270640459095, 3359.1266270769775, 3365.6129545102135, 3402.625711171769, 3541.610422853484, 66525.0, 102943.0, 103715.0, 107057.0, 107700.46149769358, 52438.0, 54084.91830163375, 54602.72698636561, 55633.44366556364, 58460.41539584635, 43823.34264986028, 53599.0, 75519.9999999989, 76025.0, 76300.61849690716, 6487.124238227968, 6687.164104486939, 6833.760818371333, 6976.965115174392, 7088.858222835617, 46245.0, 46967.29549056698, 47346.526534734214, 47651.0, 49074.509254906996, 59707.70146149241, 59710.40289597222, 62110.0, 68294.31705682879, 69701.0, 10502.579896804042, 11051.668449946514, 11067.169962253025, 11135.0, 11331.490082946122, 11675.824862626898, 11864.584739534075, 12065.0, 12329.568465103679, 12562.43469043877, 10444.791104177722, 10975.945120274348, 11561.0, 11592.710182245512, 12279.631611051684, 11146.721331966766, 11862.762744745229, 12107.636770896886, 12414.317212553397, 13012.8048079277, 11374.146938979775, 11485.712857178303, 12068.215565988183, 12075.033997280372, 12378.0, 9135.58889849945, 10205.183585313305, 10276.434796086287, 10385.169186464913, 10915.34507929495, 10047.698569042941, 10136.594536218468, 10151.0, 10332.225083118647, 10610.204234682275], "value": 6280.804114452385, "unit": "score"}], "name": "dromaeo_css", "value": 6279.940410136646, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 117.0, 117.0, 115.0, 117.0, 115.0, 117.0, 115.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 122.0, 121.0, 159.0, 125.0, 122.0, 128.0, 127.0, 151.0, 126.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 116.0, 76.0, 79.0, 79.0, 77.0, 123.0, 78.0], "value": 85.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 117.0, 84.0, 85.0, 84.0, 111.0, 83.0, 83.0, 119.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 83.0, 83.0, 82.0, 82.0, 82.0, 82.0, 81.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 141.0, 134.0, 139.0, 131.0, 132.0, 131.0, 171.0, 133.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 174.0, 173.0, 175.0, 177.0, 174.0, 175.0, 181.0, 174.0, 174.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 74.0, 68.0, 69.0, 74.0, 70.0, 70.0, 69.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1547.0999999999997, "alertThreshold": 2.0}]} 01:04:55 INFO - Return code: 0 01:04:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:04:55 INFO - # TBPL SUCCESS # 01:04:55 INFO - Running post-action listener: _resource_record_post_action 01:04:55 INFO - [mozharness: 2016-09-18 08:04:55.429022Z] Finished run-tests step (success) 01:04:55 INFO - Running post-run listener: _resource_record_post_run 01:04:55 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 7991296; Write bytes: 416702464; Read time: 2340; Write time: 441860 01:04:55 INFO - TinderboxPrint: CPU usage
13.1% 01:04:55 INFO - TinderboxPrint: I/O read bytes / time
7,991,296 / 2,340 01:04:55 INFO - TinderboxPrint: I/O write bytes / time
416,702,464 / 441,860 01:04:55 INFO - TinderboxPrint: CPU idle
8,346.8 (86.9%) 01:04:55 INFO - TinderboxPrint: CPU user
1,204.4 (12.5%) 01:04:55 INFO - TinderboxPrint: Swap in / out
0 / 0 01:04:55 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 91738112; Read time: 32; Write time: 88088 01:04:55 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 5615616; Write bytes: 324964352; Read time: 1768; Write time: 353772 01:04:56 INFO - Running post-run listener: _upload_blobber_files 01:04:56 INFO - Blob upload gear active. 01:04:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:04:56 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 01:04:56 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'] 01:04:56 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 01:04:56 INFO - (blobuploader) - INFO - Open directory for files ... 01:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:04:59 INFO - (blobuploader) - INFO - Done attempting. 01:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:05:00 INFO - (blobuploader) - INFO - Done attempting. 01:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:05:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:05:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:05:01 INFO - (blobuploader) - INFO - Done attempting. 01:05:01 INFO - (blobuploader) - INFO - Iteration through files over. 01:05:01 INFO - Return code: 0 01:05:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:05:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:05:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/09752a7aae9b62898467a33dffbf03f7dc8d4b981abfad8ee81b66d3f86c687c56769eadec0b1379fd2cbf4ee95621ec17c9519c952bcbdb850dbc0a7709bdc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ea21e96ac07f87684386f99b3d4fe91df72a6abef21aea1ffac0e62aad2625c768949a48861dca141783f4ffcc5e96b763079d015b62ebdbcc628fde9c8d885a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d062106a15c3d373ba1deedb2a4ba471133496ce4006b10e54c43adb3460d25f38b5e337a3b9843861bdfe790bf062ee3274abb1d337e190f69081e8277c23e"} 01:05:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:05:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:05:01 INFO - Contents: 01:05:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/09752a7aae9b62898467a33dffbf03f7dc8d4b981abfad8ee81b66d3f86c687c56769eadec0b1379fd2cbf4ee95621ec17c9519c952bcbdb850dbc0a7709bdc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ea21e96ac07f87684386f99b3d4fe91df72a6abef21aea1ffac0e62aad2625c768949a48861dca141783f4ffcc5e96b763079d015b62ebdbcc628fde9c8d885a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d062106a15c3d373ba1deedb2a4ba471133496ce4006b10e54c43adb3460d25f38b5e337a3b9843861bdfe790bf062ee3274abb1d337e190f69081e8277c23e"} 01:05:01 INFO - Running post-run listener: copy_logs_to_upload_dir 01:05:01 INFO - Copying logs to upload dir... 01:05:01 INFO - mkdir: /builds/slave/test/build/upload/logs 01:05:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.389701 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-18 01:05:01.531444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 01:05:01.535182) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 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/09752a7aae9b62898467a33dffbf03f7dc8d4b981abfad8ee81b66d3f86c687c56769eadec0b1379fd2cbf4ee95621ec17c9519c952bcbdb850dbc0a7709bdc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ea21e96ac07f87684386f99b3d4fe91df72a6abef21aea1ffac0e62aad2625c768949a48861dca141783f4ffcc5e96b763079d015b62ebdbcc628fde9c8d885a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d062106a15c3d373ba1deedb2a4ba471133496ce4006b10e54c43adb3460d25f38b5e337a3b9843861bdfe790bf062ee3274abb1d337e190f69081e8277c23e"} 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.011282 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/09752a7aae9b62898467a33dffbf03f7dc8d4b981abfad8ee81b66d3f86c687c56769eadec0b1379fd2cbf4ee95621ec17c9519c952bcbdb850dbc0a7709bdc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ea21e96ac07f87684386f99b3d4fe91df72a6abef21aea1ffac0e62aad2625c768949a48861dca141783f4ffcc5e96b763079d015b62ebdbcc628fde9c8d885a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d062106a15c3d373ba1deedb2a4ba471133496ce4006b10e54c43adb3460d25f38b5e337a3b9843861bdfe790bf062ee3274abb1d337e190f69081e8277c23e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 01:05:01.561256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 01:05:01.561647) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474177440.923228-1480698740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004351 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 01:05:01.644929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 01:05:01.645295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 01:05:01.645649) ========= ========= Total master_lag: 0.16 =========