builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1474134069.51 results: success (0) buildid: 20160917074612 builduid: 57deb7b16e97475da7178cf427579055 revision: f2fe5345c13ff5ec70dc259d653298a7cac61423 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.510055) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.511082) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.511717) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.529159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.529721) ========= 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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 10:41: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-09-17 10:41:09 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127180 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.673140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.673544) ========= 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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015829 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.723233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:09.723743) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f2fe5345c13ff5ec70dc259d653298a7cac61423 --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 f2fe5345c13ff5ec70dc259d653298a7cac61423 --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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 10:41:10,035 truncating revision to first 12 chars 2016-09-17 10:41:10,035 Setting DEBUG logging. 2016-09-17 10:41:10,035 attempt 1/10 2016-09-17 10:41:10,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f2fe5345c13f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 10:41:10,643 unpacking tar archive at: fx-team-f2fe5345c13f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.952036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:10.693658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:10.694460) ========= script_repo_revision: f2fe5345c13ff5ec70dc259d653298a7cac61423 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:10.695499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:10.696165) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 10:41:10.708173) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 10:41:10.708489) ========= /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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:41:11 INFO - MultiFileLogger online at 20160917 10:41:11 in /builds/slave/test 10:41: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 10:41:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:41:11 INFO - {'append_to_log': False, 10:41:11 INFO - 'base_work_dir': '/builds/slave/test', 10:41:11 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 10:41:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:41:11 INFO - 'branch': 'Fx-Team-Non-PGO', 10:41:11 INFO - 'buildbot_json_path': 'buildprops.json', 10:41:11 INFO - 'config_files': ('talos/linux_config.py',), 10:41:11 INFO - 'default_actions': ('clobber', 10:41:11 INFO - 'read-buildbot-config', 10:41:11 INFO - 'download-and-extract', 10:41:11 INFO - 'populate-webroot', 10:41:11 INFO - 'create-virtualenv', 10:41:11 INFO - 'install', 10:41:11 INFO - 'run-tests'), 10:41:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:41:11 INFO - 'download_minidump_stackwalk': True, 10:41:11 INFO - 'download_symbols': 'ondemand', 10:41:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:41:11 INFO - 'tooltool.py': '/tools/tooltool.py', 10:41:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:41:11 INFO - '/tools/misc-python/virtualenv.py')}, 10:41:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:41:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:41:11 INFO - 'installer_path': 'installer.exe', 10:41:11 INFO - 'log_level': 'info', 10:41:11 INFO - 'log_name': 'talos', 10:41:11 INFO - 'log_to_console': True, 10:41:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:41:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:41:11 INFO - 'opt_config_files': (), 10:41:11 INFO - 'pip_index': False, 10:41:11 INFO - 'sps_profile': False, 10:41:11 INFO - 'sps_profile_interval': 0, 10:41:11 INFO - 'suite': 'dromaeojs', 10:41:11 INFO - 'system_bits': '32', 10:41:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:41:11 INFO - 'title': 'talos-linux64-ix-055', 10:41:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:41:11 INFO - 'use_talos_json': True, 10:41:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:41:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:41:11 INFO - 'work_dir': 'build'} 10:41:11 INFO - [mozharness: 2016-09-17 17:41:11.118313Z] Running clobber step. 10:41:11 INFO - Running pre-action listener: _resource_record_pre_action 10:41:11 INFO - Running main action method: clobber 10:41:11 INFO - rmtree: /builds/slave/test/build 10:41:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:41:11 INFO - Running post-action listener: _resource_record_post_action 10:41:11 INFO - [mozharness: 2016-09-17 17:41:11.575743Z] Finished clobber step (success) 10:41:11 INFO - [mozharness: 2016-09-17 17:41:11.575844Z] Running read-buildbot-config step. 10:41:11 INFO - Running pre-action listener: _resource_record_pre_action 10:41:11 INFO - Running main action method: read_buildbot_config 10:41:11 INFO - Using buildbot properties: 10:41:11 INFO - { 10:41:11 INFO - "project": "", 10:41:11 INFO - "product": "firefox", 10:41:11 INFO - "script_repo_revision": "production", 10:41:11 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 10:41:11 INFO - "repository": "", 10:41:11 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 10:41:11 INFO - "buildid": "20160917074612", 10:41:11 INFO - "stage_platform": "linux64", 10:41:11 INFO - "basedir": "/builds/slave/test", 10:41:11 INFO - "buildnumber": 2165, 10:41:11 INFO - "platform": "ubuntu64_hw", 10:41:11 INFO - "mozci_request": { 10:41:11 INFO - "priority": -1, 10:41:11 INFO - "type": "trigger_all_talos_jobs", 10:41:11 INFO - "times": 6 10:41:11 INFO - }, 10:41:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:41:11 INFO - "slavebuilddir": "test", 10:41:11 INFO - "branch": "fx-team", 10:41:11 INFO - "revision": "f2fe5345c13ff5ec70dc259d653298a7cac61423", 10:41:11 INFO - "slavename": "talos-linux64-ix-055", 10:41:11 INFO - "builduid": "57deb7b16e97475da7178cf427579055", 10:41:11 INFO - "repo_path": "integration/fx-team" 10:41:11 INFO - } 10:41:11 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 10:41:11 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 10:41:11 INFO - Running post-action listener: _resource_record_post_action 10:41:11 INFO - [mozharness: 2016-09-17 17:41:11.578606Z] Finished read-buildbot-config step (success) 10:41:11 INFO - [mozharness: 2016-09-17 17:41:11.578695Z] Running download-and-extract step. 10:41:11 INFO - Running pre-action listener: _resource_record_pre_action 10:41:11 INFO - Running main action method: download_and_extract 10:41:11 INFO - mkdir: /builds/slave/test/build/tests 10:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:11 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:41:11 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/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 10:41:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/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 10:41:11 INFO - Downloaded 1361 bytes. 10:41:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:41:11 INFO - Using the following test package requirements: 10:41:11 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:41:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:41:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:41:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'jsshell-linux-x86_64.zip'], 10:41:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:41:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:41:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:41:11 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:41:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:41:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:41:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:41:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:41:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:41:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:41:11 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:41:11 INFO - Expected file size: 20365791 10:41:15 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 10:41:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:41:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:41:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:41:15 INFO - Expected file size: 20365791 10:41:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:41:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:41:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 10:41:20 INFO - Expected file size: 14414048 10:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:21 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:41:21 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/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 10:41:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/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 10:41:21 INFO - Downloaded 55593420 bytes. 10:41:21 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:41:21 INFO - mkdir: /builds/slave/test/properties 10:41:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:21 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:21 INFO - Contents: 10:41:21 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:41:21 INFO - Running post-action listener: _resource_record_post_action 10:41:21 INFO - Running post-action listener: set_extra_try_arguments 10:41:21 INFO - [mozharness: 2016-09-17 17:41:21.977575Z] Finished download-and-extract step (success) 10:41:21 INFO - [mozharness: 2016-09-17 17:41:21.977803Z] Running populate-webroot step. 10:41:21 INFO - Running pre-action listener: _resource_record_pre_action 10:41:21 INFO - Running main action method: populate_webroot 10:41:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:21 INFO - '--tppagecycles', 10:41:21 INFO - '7'], 10:41:21 INFO - 'tests': ['tsvgm']}}, 10:41:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['damp', 'tps']}, 10:41:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'tests': ['damp', 'tps']}, 10:41:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['dromaeo_dom']}, 10:41:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['basic_compositor_video']}, 10:41:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['a11yr', 10:41:21 INFO - 'ts_paint', 10:41:21 INFO - 'tpaint', 10:41:21 INFO - 'sessionrestore', 10:41:21 INFO - 'sessionrestore_no_auto_restore', 10:41:21 INFO - 'tabpaint']}, 10:41:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['tsvgx', 10:41:21 INFO - 'tsvgr_opacity', 10:41:21 INFO - 'tart', 10:41:21 INFO - 'tscrollx', 10:41:21 INFO - 'cart']}, 10:41:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:21 INFO - 'tsvgr_opacity', 10:41:21 INFO - 'tart', 10:41:21 INFO - 'tscrollx', 10:41:21 INFO - 'cart']}, 10:41:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'talos_options': ['--disable-e10s'], 10:41:21 INFO - 'tests': ['tp5o']}, 10:41:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'tests': ['tp5o']}, 10:41:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'talos_options': ['--disable-e10s', 10:41:21 INFO - '--xperf_path', 10:41:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:21 INFO - 'tests': ['tp5n']}, 10:41:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:21 INFO - 'talos_options': ['--xperf_path', 10:41:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:21 INFO - 'tests': ['tp5n']}}, 10:41:21 INFO - 'talos.zip': {'path': '', 10:41:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:21 INFO - Running post-action listener: _resource_record_post_action 10:41:21 INFO - [mozharness: 2016-09-17 17:41:21.998908Z] Finished populate-webroot step (success) 10:41:21 INFO - [mozharness: 2016-09-17 17:41:21.998996Z] Running create-virtualenv step. 10:41:21 INFO - Running pre-action listener: _resource_record_pre_action 10:41:21 INFO - Running main action method: create_virtualenv 10:41:21 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:21 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 10:41:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:22 INFO - Using real prefix '/usr' 10:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:22 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:24 INFO - Installing pip.................done. 10:41:24 INFO - Return code: 0 10:41:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:41:24 INFO - Using env: {'DISPLAY': ':0', 10:41:24 INFO - 'HOME': '/home/cltbld', 10:41:24 INFO - 'LANG': 'en_US.UTF-8', 10:41:24 INFO - 'LANGUAGE': 'en_US:en', 10:41:24 INFO - 'LOGNAME': 'cltbld', 10:41:24 INFO - 'MAIL': '/var/mail/cltbld', 10:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:24 INFO - 'MOZ_NO_REMOTE': '1', 10:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:24 INFO - 'NO_EM_RESTART': '1', 10:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:24 INFO - 'PWD': '/builds/slave/test', 10:41:24 INFO - 'SHELL': '/bin/bash', 10:41:24 INFO - 'SHLVL': '1', 10:41:24 INFO - 'TERM': 'linux', 10:41:24 INFO - 'TMOUT': '86400', 10:41:24 INFO - 'USER': 'cltbld', 10:41:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:24 INFO - '_': '/tools/buildbot/bin/python'} 10:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:24 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 10:41:24 INFO - Cleaning up... 10:41:24 INFO - Return code: 0 10:41:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:41:24 INFO - Using env: {'DISPLAY': ':0', 10:41:24 INFO - 'HOME': '/home/cltbld', 10:41:24 INFO - 'LANG': 'en_US.UTF-8', 10:41:24 INFO - 'LANGUAGE': 'en_US:en', 10:41:24 INFO - 'LOGNAME': 'cltbld', 10:41:24 INFO - 'MAIL': '/var/mail/cltbld', 10:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:24 INFO - 'MOZ_NO_REMOTE': '1', 10:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:24 INFO - 'NO_EM_RESTART': '1', 10:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:24 INFO - 'PWD': '/builds/slave/test', 10:41:24 INFO - 'SHELL': '/bin/bash', 10:41:24 INFO - 'SHLVL': '1', 10:41:24 INFO - 'TERM': 'linux', 10:41:24 INFO - 'TMOUT': '86400', 10:41:24 INFO - 'USER': 'cltbld', 10:41:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:24 INFO - '_': '/tools/buildbot/bin/python'} 10:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:25 INFO - Downloading/unpacking psutil>=3.1.1 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:27 INFO - Installing collected packages: psutil 10:41:27 INFO - Running setup.py install for psutil 10:41:27 INFO - building 'psutil._psutil_linux' extension 10:41:27 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 10:41:27 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 10:41:27 INFO - building 'psutil._psutil_posix' extension 10:41:27 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 10:41:27 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 10:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:27 INFO - Successfully installed psutil 10:41:27 INFO - Cleaning up... 10:41:27 INFO - Return code: 0 10:41:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:27 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')]} 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:27 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')]} 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:27 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:27 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 10:41:27 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 10:41:27 INFO - Using env: {'DISPLAY': ':0', 10:41:27 INFO - 'HOME': '/home/cltbld', 10:41:27 INFO - 'LANG': 'en_US.UTF-8', 10:41:27 INFO - 'LANGUAGE': 'en_US:en', 10:41:27 INFO - 'LOGNAME': 'cltbld', 10:41:27 INFO - 'MAIL': '/var/mail/cltbld', 10:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:27 INFO - 'MOZ_NO_REMOTE': '1', 10:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:27 INFO - 'NO_EM_RESTART': '1', 10:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:27 INFO - 'PWD': '/builds/slave/test', 10:41:27 INFO - 'SHELL': '/bin/bash', 10:41:27 INFO - 'SHLVL': '1', 10:41:27 INFO - 'TERM': 'linux', 10:41:27 INFO - 'TMOUT': '86400', 10:41:27 INFO - 'USER': 'cltbld', 10:41:27 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:27 INFO - '_': '/tools/buildbot/bin/python'} 10:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:29 INFO - Installing collected packages: mozsystemmonitor 10:41:29 INFO - Running setup.py install for mozsystemmonitor 10:41:29 INFO - Successfully installed mozsystemmonitor 10:41:29 INFO - Cleaning up... 10:41:29 INFO - Return code: 0 10:41:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:41:29 INFO - Using env: {'DISPLAY': ':0', 10:41:29 INFO - 'HOME': '/home/cltbld', 10:41:29 INFO - 'LANG': 'en_US.UTF-8', 10:41:29 INFO - 'LANGUAGE': 'en_US:en', 10:41:29 INFO - 'LOGNAME': 'cltbld', 10:41:29 INFO - 'MAIL': '/var/mail/cltbld', 10:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:29 INFO - 'MOZ_NO_REMOTE': '1', 10:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:29 INFO - 'NO_EM_RESTART': '1', 10:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:29 INFO - 'PWD': '/builds/slave/test', 10:41:29 INFO - 'SHELL': '/bin/bash', 10:41:29 INFO - 'SHLVL': '1', 10:41:29 INFO - 'TERM': 'linux', 10:41:29 INFO - 'TMOUT': '86400', 10:41:29 INFO - 'USER': 'cltbld', 10:41:29 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:29 INFO - '_': '/tools/buildbot/bin/python'} 10:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:30 INFO - Downloading/unpacking jsonschema==2.5.1 10:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41:32 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:32 INFO - warning: no files found matching '*.txt' 10:41:32 INFO - no previously-included directories found matching 'build' 10:41:32 INFO - no previously-included directories found matching 'dist' 10:41:32 INFO - no previously-included directories found matching '.git*' 10:41:32 INFO - Installing collected packages: jsonschema, functools32 10:41:32 INFO - Running setup.py install for functools32 10:41:32 INFO - warning: no files found matching '*.txt' 10:41:32 INFO - no previously-included directories found matching 'build' 10:41:32 INFO - no previously-included directories found matching 'dist' 10:41:32 INFO - no previously-included directories found matching '.git*' 10:41:32 INFO - Successfully installed jsonschema functools32 10:41:32 INFO - Cleaning up... 10:41:32 INFO - Return code: 0 10:41:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:41:32 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')]} 10:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:32 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')]} 10:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:32 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:32 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 10:41:32 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 10:41:32 INFO - Using env: {'DISPLAY': ':0', 10:41:32 INFO - 'HOME': '/home/cltbld', 10:41:32 INFO - 'LANG': 'en_US.UTF-8', 10:41:32 INFO - 'LANGUAGE': 'en_US:en', 10:41:32 INFO - 'LOGNAME': 'cltbld', 10:41:32 INFO - 'MAIL': '/var/mail/cltbld', 10:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:32 INFO - 'MOZ_NO_REMOTE': '1', 10:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:32 INFO - 'NO_EM_RESTART': '1', 10:41:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:32 INFO - 'PWD': '/builds/slave/test', 10:41:32 INFO - 'SHELL': '/bin/bash', 10:41:32 INFO - 'SHLVL': '1', 10:41:32 INFO - 'TERM': 'linux', 10:41:32 INFO - 'TMOUT': '86400', 10:41:32 INFO - 'USER': 'cltbld', 10:41:32 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:32 INFO - '_': '/tools/buildbot/bin/python'} 10:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:41:32 INFO - Cleaning up... 10:41:32 INFO - Return code: 0 10:41:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41:32 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')]} 10:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:32 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')]} 10:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:32 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:32 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 10:41:32 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 10:41:32 INFO - Using env: {'DISPLAY': ':0', 10:41:32 INFO - 'HOME': '/home/cltbld', 10:41:32 INFO - 'LANG': 'en_US.UTF-8', 10:41:32 INFO - 'LANGUAGE': 'en_US:en', 10:41:32 INFO - 'LOGNAME': 'cltbld', 10:41:32 INFO - 'MAIL': '/var/mail/cltbld', 10:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:32 INFO - 'MOZ_NO_REMOTE': '1', 10:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:32 INFO - 'NO_EM_RESTART': '1', 10:41:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:32 INFO - 'PWD': '/builds/slave/test', 10:41:32 INFO - 'SHELL': '/bin/bash', 10:41:32 INFO - 'SHLVL': '1', 10:41:32 INFO - 'TERM': 'linux', 10:41:32 INFO - 'TMOUT': '86400', 10:41:32 INFO - 'USER': 'cltbld', 10:41:32 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:32 INFO - '_': '/tools/buildbot/bin/python'} 10:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:32 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:34 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41: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 10:41: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 10:41: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 10:41:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41: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 10:41: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 10:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41: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 10:41: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 10:41:35 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 10:41: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 10:41: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 10:41: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 10:41:35 INFO - Downloading docopt-0.6.1.tar.gz 10:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:35 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:35 INFO - Running setup.py install for blobuploader 10:41:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:35 INFO - Running setup.py install for requests 10:41:35 INFO - Running setup.py install for docopt 10:41:36 INFO - Successfully installed blobuploader requests docopt 10:41:36 INFO - Cleaning up... 10:41:36 INFO - Return code: 0 10:41:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:36 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:36 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 10:41:36 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 10:41:36 INFO - Using env: {'DISPLAY': ':0', 10:41:36 INFO - 'HOME': '/home/cltbld', 10:41:36 INFO - 'LANG': 'en_US.UTF-8', 10:41:36 INFO - 'LANGUAGE': 'en_US:en', 10:41:36 INFO - 'LOGNAME': 'cltbld', 10:41:36 INFO - 'MAIL': '/var/mail/cltbld', 10:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:36 INFO - 'MOZ_NO_REMOTE': '1', 10:41:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:36 INFO - 'NO_EM_RESTART': '1', 10:41:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:36 INFO - 'PWD': '/builds/slave/test', 10:41:36 INFO - 'SHELL': '/bin/bash', 10:41:36 INFO - 'SHLVL': '1', 10:41:36 INFO - 'TERM': 'linux', 10:41:36 INFO - 'TMOUT': '86400', 10:41:36 INFO - 'USER': 'cltbld', 10:41:36 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:36 INFO - '_': '/tools/buildbot/bin/python'} 10:41:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:36 INFO - Running setup.py (path:/tmp/pip-C3Fa4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:36 INFO - Running setup.py (path:/tmp/pip-OtXFyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:36 INFO - Running setup.py (path:/tmp/pip-7C0ChP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:36 INFO - Running setup.py (path:/tmp/pip-yefOp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:36 INFO - Running setup.py (path:/tmp/pip-mq2byl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:36 INFO - Running setup.py (path:/tmp/pip-nIQ4iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:36 INFO - Running setup.py (path:/tmp/pip-FKDTFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:36 INFO - Running setup.py (path:/tmp/pip-AnAo8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:36 INFO - Running setup.py (path:/tmp/pip-ELfEXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:36 INFO - Running setup.py (path:/tmp/pip-eF2Uo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:37 INFO - Running setup.py (path:/tmp/pip-bnma_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:37 INFO - Running setup.py (path:/tmp/pip-sro4vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:37 INFO - Running setup.py (path:/tmp/pip-P5i9JU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:37 INFO - Running setup.py (path:/tmp/pip-uKEaeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:37 INFO - Running setup.py (path:/tmp/pip-yKUkTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:37 INFO - Running setup.py (path:/tmp/pip-SLvGNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:37 INFO - Running setup.py (path:/tmp/pip-P70sLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:37 INFO - Running setup.py install for manifestparser 10:41:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:37 INFO - Running setup.py install for mozcrash 10:41:37 INFO - Running setup.py install for mozdebug 10:41:37 INFO - Running setup.py install for mozdevice 10:41:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:37 INFO - Running setup.py install for mozfile 10:41:38 INFO - Running setup.py install for mozhttpd 10:41:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozinfo 10:41:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozInstall 10:41:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozleak 10:41:38 INFO - Running setup.py install for mozlog 10:41:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for moznetwork 10:41:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozprocess 10:41:38 INFO - Running setup.py install for mozprofile 10:41:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozrunner 10:41:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:41:38 INFO - Running setup.py install for mozscreenshot 10:41:39 INFO - Running setup.py install for moztest 10:41:39 INFO - Running setup.py install for mozversion 10:41:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:41:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:39 INFO - Cleaning up... 10:41:39 INFO - Return code: 0 10:41:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:39 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:39 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 10:41:39 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 10:41:39 INFO - Using env: {'DISPLAY': ':0', 10:41:39 INFO - 'HOME': '/home/cltbld', 10:41:39 INFO - 'LANG': 'en_US.UTF-8', 10:41:39 INFO - 'LANGUAGE': 'en_US:en', 10:41:39 INFO - 'LOGNAME': 'cltbld', 10:41:39 INFO - 'MAIL': '/var/mail/cltbld', 10:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:39 INFO - 'MOZ_NO_REMOTE': '1', 10:41:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:39 INFO - 'NO_EM_RESTART': '1', 10:41:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:39 INFO - 'PWD': '/builds/slave/test', 10:41:39 INFO - 'SHELL': '/bin/bash', 10:41:39 INFO - 'SHLVL': '1', 10:41:39 INFO - 'TERM': 'linux', 10:41:39 INFO - 'TMOUT': '86400', 10:41:39 INFO - 'USER': 'cltbld', 10:41:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:39 INFO - '_': '/tools/buildbot/bin/python'} 10:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:39 INFO - Running setup.py (path:/tmp/pip-jBwAaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:39 INFO - Running setup.py (path:/tmp/pip-1166yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:39 INFO - Running setup.py (path:/tmp/pip-MTa0rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:39 INFO - Running setup.py (path:/tmp/pip-NTJrt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:39 INFO - Running setup.py (path:/tmp/pip-7pzEqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:39 INFO - Running setup.py (path:/tmp/pip-35FY7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:39 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)) 10:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:39 INFO - Running setup.py (path:/tmp/pip-41pSr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:40 INFO - Running setup.py (path:/tmp/pip-ntgzSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:40 INFO - Running setup.py (path:/tmp/pip-sBIzlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:40 INFO - Running setup.py (path:/tmp/pip-AthefY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:40 INFO - Running setup.py (path:/tmp/pip-5lT4BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:40 INFO - Running setup.py (path:/tmp/pip-cguq8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:40 INFO - Running setup.py (path:/tmp/pip-sUXQ2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:40 INFO - Running setup.py (path:/tmp/pip-ch3nt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:40 INFO - Running setup.py (path:/tmp/pip-uhotM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:40 INFO - Running setup.py (path:/tmp/pip-JRyX9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:40 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)) 10:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:40 INFO - Running setup.py (path:/tmp/pip-pw4JNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41: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 10:41:42 INFO - Downloading blessings-1.6.tar.gz 10:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:41:42 INFO - Installing collected packages: blessings 10:41:42 INFO - Running setup.py install for blessings 10:41:42 INFO - Successfully installed blessings 10:41:42 INFO - Cleaning up... 10:41:42 INFO - Return code: 0 10:41:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:43 INFO - Reading from file tmpfile_stdout 10:41:43 INFO - Current package versions: 10:41:43 INFO - argparse == 1.2.1 10:41:43 INFO - blessings == 1.6 10:41:43 INFO - blobuploader == 1.2.4 10:41:43 INFO - docopt == 0.6.1 10:41:43 INFO - functools32 == 3.2.3-2 10:41:43 INFO - jsonschema == 2.5.1 10:41:43 INFO - manifestparser == 1.1 10:41:43 INFO - mozInstall == 1.12 10:41:43 INFO - mozcrash == 0.17 10:41:43 INFO - mozdebug == 0.1 10:41:43 INFO - mozdevice == 0.48 10:41:43 INFO - mozfile == 1.2 10:41:43 INFO - mozhttpd == 0.7 10:41:43 INFO - mozinfo == 0.9 10:41:43 INFO - mozleak == 0.1 10:41:43 INFO - mozlog == 3.4 10:41:43 INFO - moznetwork == 0.27 10:41:43 INFO - mozprocess == 0.23 10:41:43 INFO - mozprofile == 0.28 10:41:43 INFO - mozrunner == 6.12 10:41:43 INFO - mozscreenshot == 0.1 10:41:43 INFO - mozsystemmonitor == 0.3 10:41:43 INFO - moztest == 0.7 10:41:43 INFO - mozversion == 1.4 10:41:43 INFO - psutil == 3.1.1 10:41:43 INFO - requests == 1.2.3 10:41:43 INFO - wsgiref == 0.1.2 10:41:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:43 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:43 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 10:41:43 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 10:41:43 INFO - Using env: {'DISPLAY': ':0', 10:41:43 INFO - 'HOME': '/home/cltbld', 10:41:43 INFO - 'LANG': 'en_US.UTF-8', 10:41:43 INFO - 'LANGUAGE': 'en_US:en', 10:41:43 INFO - 'LOGNAME': 'cltbld', 10:41:43 INFO - 'MAIL': '/var/mail/cltbld', 10:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:43 INFO - 'MOZ_NO_REMOTE': '1', 10:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:43 INFO - 'NO_EM_RESTART': '1', 10:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:43 INFO - 'PWD': '/builds/slave/test', 10:41:43 INFO - 'SHELL': '/bin/bash', 10:41:43 INFO - 'SHLVL': '1', 10:41:43 INFO - 'TERM': 'linux', 10:41:43 INFO - 'TMOUT': '86400', 10:41:43 INFO - 'USER': 'cltbld', 10:41:43 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:43 INFO - '_': '/tools/buildbot/bin/python'} 10:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 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)) 10:41:43 INFO - Cleaning up... 10:41:43 INFO - Return code: 0 10:41:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:43 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 0x7f37a2192f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', '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 10:41:43 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 10:41:43 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 10:41:43 INFO - Using env: {'DISPLAY': ':0', 10:41:43 INFO - 'HOME': '/home/cltbld', 10:41:43 INFO - 'LANG': 'en_US.UTF-8', 10:41:43 INFO - 'LANGUAGE': 'en_US:en', 10:41:43 INFO - 'LOGNAME': 'cltbld', 10:41:43 INFO - 'MAIL': '/var/mail/cltbld', 10:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:43 INFO - 'MOZ_NO_REMOTE': '1', 10:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:43 INFO - 'NO_EM_RESTART': '1', 10:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:43 INFO - 'PWD': '/builds/slave/test', 10:41:43 INFO - 'SHELL': '/bin/bash', 10:41:43 INFO - 'SHLVL': '1', 10:41:43 INFO - 'TERM': 'linux', 10:41:43 INFO - 'TMOUT': '86400', 10:41:43 INFO - 'USER': 'cltbld', 10:41:43 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:43 INFO - '_': '/tools/buildbot/bin/python'} 10:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:41:43 INFO - Cleaning up... 10:41:43 INFO - Return code: 0 10:41:43 INFO - Running post-action listener: _resource_record_post_action 10:41:43 INFO - Running post-action listener: _start_resource_monitoring 10:41:43 INFO - Starting resource monitoring. 10:41:43 INFO - [mozharness: 2016-09-17 17:41:43.526042Z] Finished create-virtualenv step (success) 10:41:43 INFO - [mozharness: 2016-09-17 17:41:43.526496Z] Running install step. 10:41:43 INFO - Running pre-action listener: _resource_record_pre_action 10:41:43 INFO - Running main action method: install 10:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:43 INFO - Reading from file tmpfile_stdout 10:41:43 INFO - Detecting whether we're running mozinstall >=1.0... 10:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:41:43 INFO - Reading from file tmpfile_stdout 10:41:43 INFO - Output received: 10:41:43 INFO - Usage: mozinstall [options] installer 10:41:43 INFO - Options: 10:41:43 INFO - -h, --help show this help message and exit 10:41:43 INFO - -d DEST, --destination=DEST 10:41:43 INFO - Directory to install application into. [default: 10:41:43 INFO - "/builds/slave/test"] 10:41:43 INFO - --app=APP Application being installed. [default: firefox] 10:41:43 INFO - mkdir: /builds/slave/test/build/application 10:41:43 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'] 10:41:43 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 10:41:59 INFO - Reading from file tmpfile_stdout 10:41:59 INFO - Output received: 10:41:59 INFO - /builds/slave/test/build/application/firefox/firefox 10:41:59 INFO - Running post-action listener: _resource_record_post_action 10:41:59 INFO - [mozharness: 2016-09-17 17:41:59.851724Z] Finished install step (success) 10:41:59 INFO - [mozharness: 2016-09-17 17:41:59.851970Z] Running run-tests step. 10:41:59 INFO - Running pre-action listener: _resource_record_pre_action 10:41:59 INFO - Running main action method: run_tests 10:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:41:59 INFO - Python 2.7.3 10:41:59 INFO - Return code: 0 10:41:59 INFO - grabbing minidump binary from tooltool 10:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:59 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 0xdf8f60>, '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 0xdf9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdfa1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:41:59 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 10:41:59 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 10:41:59 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 10:41:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:41:59 INFO - Return code: 0 10:41:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:41:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:41:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:41:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:41:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 10:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 10:41:59 INFO - Using env: {'DISPLAY': ':0', 10:41:59 INFO - 'HOME': '/home/cltbld', 10:41:59 INFO - 'LANG': 'en_US.UTF-8', 10:41:59 INFO - 'LANGUAGE': 'en_US:en', 10:41:59 INFO - 'LOGNAME': 'cltbld', 10:41:59 INFO - 'MAIL': '/var/mail/cltbld', 10:41:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:41:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:59 INFO - 'MOZ_NO_REMOTE': '1', 10:41:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:59 INFO - 'NO_EM_RESTART': '1', 10:41:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:59 INFO - 'PWD': '/builds/slave/test', 10:41:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:41:59 INFO - 'SHELL': '/bin/bash', 10:41:59 INFO - 'SHLVL': '1', 10:41:59 INFO - 'TERM': 'linux', 10:41:59 INFO - 'TMOUT': '86400', 10:41:59 INFO - 'USER': 'cltbld', 10:41:59 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238', 10:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:59 INFO - '_': '/tools/buildbot/bin/python'} 10:41:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 10:42:00 INFO - mozversion application_buildid: 20160916143618 10:42:00 INFO - mozversion application_changeset: f2fe5345c13ff5ec70dc259d653298a7cac61423 10:42:00 INFO - mozversion application_display_name: Nightly 10:42:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:42:00 INFO - mozversion application_name: Firefox 10:42:00 INFO - mozversion application_remotingname: firefox 10:42:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:42:00 INFO - mozversion application_vendor: Mozilla 10:42:00 INFO - mozversion application_version: 51.0a1 10:42:00 INFO - mozversion platform_buildid: 20160916143618 10:42:00 INFO - mozversion platform_changeset: f2fe5345c13ff5ec70dc259d653298a7cac61423 10:42:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:42:00 INFO - mozversion platform_version: 51.0a1 10:42:00 INFO - using testdate: 1474134120 10:42:00 INFO - actual date: 1474134120 10:42:00 INFO - starting webserver on 'localhost:47258' 10:42:00 INFO - SUITE-START | Running 2 tests 10:42:00 INFO - TEST-START | dromaeo_css 10:42:00 INFO - operating with platform_type : linux_ 10:42:00 INFO - Initialising browser for dromaeo_css test... 10:42:00 INFO - TEST-INFO | started process 11417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnGB8i2/profile http://localhost:47258/getInfo.html) 10:42:04 INFO - PROCESS | 11417 | __metrics Screen width/height:1600/1200 10:42:04 INFO - PROCESS | 11417 | colorDepth:24 10:42:04 INFO - PROCESS | 11417 | Browser inner width/height: 1024/697 10:42:04 INFO - PROCESS | 11417 | __metrics 10:42:05 INFO - PROCESS | 11417 | 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" 10:42:05 INFO - PROCESS | 11417 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:05 INFO - PROCESS | 11417 | console.error: PushService: 10:42:05 INFO - PROCESS | 11417 | stateChangeProcessEnqueue: Error transitioning state 10:42:05 INFO - PROCESS | 11417 | UnknownError 10:42:06 INFO - TEST-INFO | 11417: exit 0 10:42:06 INFO - Browser initialized. 10:42:06 INFO - Running cycle 1/1 for dromaeo_css test... 10:42:06 INFO - TEST-INFO | started process 11498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnGB8i2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:18 INFO - PROCESS | 11498 | RSS: Main: 174510080 10:42:18 INFO - PROCESS | 11498 | 10:42:30 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:42:44 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:42:48 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 10:43:09 INFO - PROCESS | 11498 | 1474134189592 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] 10:43:09 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:43:17 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 10:43:22 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:43:25 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 10:43:47 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 10:43:55 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:43:57 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:44:06 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 10:44:11 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:44:15 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:44:17 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 10:44:19 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:44:22 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 10:44:26 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:44:34 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:45:06 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 10:45:08 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:45:22 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47258/tests/dromaeo/cssquery-ext.html) 10:45:24 INFO - PROCESS | 11498 | RSS: Main: 300740608 10:45:24 INFO - PROCESS | 11498 | 10:45:28 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:45:37 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:45:46 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 10:45:48 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:46:19 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:35 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 10:46:43 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:45 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:47 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:46:49 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:01 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:47:25 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:40 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:47:44 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:47:46 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:47:54 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:47:56 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:48:02 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:48:26 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-ext.html (next: http://localhost:47258/tests/dromaeo/cssquery-jquery.html) 10:48:27 INFO - PROCESS | 11498 | RSS: Main: 271450112 10:48:27 INFO - PROCESS | 11498 | 10:48:30 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:48:31 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:48:49 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:48:54 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:48:58 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:49:04 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:49:18 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:49:32 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:34 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:36 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:48 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:49:56 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:50:12 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:50:24 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:50:49 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:50:51 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:50:59 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:09 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:51:11 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:19 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:51:21 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:51:27 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:29 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47258/tests/dromaeo/cssquery-mootools.html) 10:51:30 INFO - PROCESS | 11498 | RSS: Main: 262422528 10:51:30 INFO - PROCESS | 11498 | 10:51:35 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 10:51:38 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 10:51:47 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:51 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:53 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:51:57 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:51:59 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:52:01 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:52:03 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:52:05 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:52:11 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:52:17 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:52:21 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:52:23 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:52:27 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 10:52:29 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:52:31 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:52:41 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:52:45 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:52:47 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:52:49 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:52:56 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:53:01 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:53:05 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:53:32 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:53:40 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:54:01 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:54:02 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 10:54:04 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:54:16 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:54:20 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:54:22 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:54:29 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:54:30 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:54:32 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:54:32 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47258/tests/dromaeo/cssquery-prototype.html) 10:54:33 INFO - PROCESS | 11498 | RSS: Main: 223756288 10:54:33 INFO - PROCESS | 11498 | 10:54:43 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:54:50 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:55:09 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 10:55:26 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 10:55:28 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:55:30 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:55:32 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:55:34 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:55:44 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:55:46 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:56:03 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:56:17 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:56:23 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:56:27 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:56:31 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:56:35 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:56:36 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 2.00004 seconds. 10:56:37 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:56:39 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:56:41 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:56:46 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:16 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:22 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:24 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:31 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 10:57:35 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:57:36 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47258/tests/dromaeo/cssquery-yui.html) 10:57:37 INFO - PROCESS | 11498 | RSS: Main: 219783168 10:57:37 INFO - PROCESS | 11498 | 10:57:40 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:57:43 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:57:53 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:57:57 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:58:05 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:58:09 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:58:11 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:58:18 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:58:22 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:58:24 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:58:26 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:58:28 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:58:32 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 10:58:40 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 10:58:42 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:58:47 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 10:58:54 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:58:58 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:59:08 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:59:11 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:59:20 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 10:59:31 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:59:33 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:59:53 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:59:57 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:59:59 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:00:01 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 11:00:14 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 11:00:28 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:00:34 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 11:00:37 INFO - PROCESS | 11498 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 11:00:40 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:47258/tests/dromaeo/cssquery-yui.html (next: http://localhost:47258/tests/dromaeo/cssquery-dojo.html) 11:00:40 INFO - PROCESS | 11498 | RSS: Main: 303644672 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | __start_tp_report 11:00:40 INFO - PROCESS | 11498 | _x_x_mozilla_page_load 11:00:40 INFO - PROCESS | 11498 | _x_x_mozilla_page_load_details 11:00:40 INFO - PROCESS | 11498 | |i|pagename|runs| 11:00:40 INFO - PROCESS | 11498 | |0;dojo.html;2592.5851863701805;2621.868906554672;2651.5094707479116;2659.6542449481562;2676.371052802591;4554.339620754997;4622.491525932145;4674.029637280997;4758.666893317465;4822.180229361008;4651.069786042796;4670.859874203771;4674.906501869969;4681.555252251031;4685.789139488723;4285.700000999924;4377.080813029268;4398.098389830077;4446.55534446556;4458.843940462085;4369.80335884886;4406.647468202536;4436.179306828231;4456.5766252205985;4485.663575231854;4395.494518130408;4453.354263631788;4487.349334346518;4512.413386259782;4516.864494065183;4418.315161150027;4439.1121775645;4471.664625153094;4510.526394728556;4524.2082635538645;4151.418801367811;4180.435641188431;4183.100633363842;4241.893952651209;4347.630451411634;4630.606398456135;4666.160091183586;4675.135100006476;4744.335792988984;4757.548032936866;4958.355413796184;4966.230234313651;4967.528084831935;4990.2265148901615;5043.949560504385;4921.581665558432;4973.875653108665;5004.874878128039;5012.749362531858;5057.645964782467;4742.241241401359;4781.617470602343;4786.210275304606;4847.587955023862;4849.80600775965;5244.501772331622;5245.527902488794;5249.186376111708;5250.317458730341;5305.124654431976;5011.022850544182;5014.498550144956;5015.247712843103;5019.247112933089;5073.391193056783;4955.35580374549;5008.223725322581;5016.372953380827;5073.923891141636;5098.388193416814;4940.728259945738;4964.702117872939;4998.075356059141;5016.423111342169;5061.974690126526;4934.9753251233515;4982.053409852189;4984.102861484895;5025.296458495814;5040.042391945519;4199.391088292181;4200.1179752252365;4214.593550304628;4255.744655320691;4268.16770729711;3647.379945409287;3662.981685091611;3672.210474747942;3702;3745.2509498099944;4306.267934451151;4359.106383191438;4376.671749618791;4379.496357918816;4381.255186618282;5045.293658887758;5047.394312682502;5081.161608334657;5095.923561146586;5109.897802043938;4370.7159034662645;4380.123975204971;4381.145676593097;4440.822367105279;4441.467023957081;4382.824687012484;4386.144701783185;4496.1906856765445;4506.3465797459185;4514.593686568159;4807.663463557517;4821.686590371614;4829.251466022632;4887.095887260726;4889.706617602955;5072.771725272445;5096.770645320896;5112.6165537585575;5140.46025167367;5176.534111929945;4705.55297246761;4752.833650822205;4819.493953135011;4832.975835120943;4885.5358740919555;4960.206366981266;5011.649184557046;5011.997600479992;5016.297718319511;5070.467600901852;5059.418166910852;5068.341115654941;5075.898482030412;5100.5409513143995;5131.512506311894;4806.134895718665;4920;4938.925916111333;4939.506049395031;4991.900161996812;4834.806607735652;4852.078105160008;4882.292067650242;4932.778024988812;5004.699718016931;4856.000118946677;4861.392325959255;4863.027394521039;4873.439554451142;4920.876978075434;4913.6069114471475;4918.901621967469;4940.901181976268;4954.083494553519;4968.8757781055765;4744.288356746378;4785.59322457581;4867.440244371801;4899.387576552931;4915.582175515121 11:00:40 INFO - PROCESS | 11498 | |1;ext.html;563.4140493886358;576.2019602850052;577.7862190989333;584.9970750146251;586.551288264478;26886.46227075457;27612.238776122376;29224.70775292249;36959;38033.61966380335;38786.836394908125;39165.41251881224;39191.804040979754;39211.607883921155;39227.21545569094;7554.96222518888;7705.383569314441;7705.961470192641;7707.113681926594;7710.074791025085;7596.278353556431;7679.424043196755;7680.423968202379;7680.615969201545;7684.193159718233;7671.578063206522;7711.845763084735;7718.768436946901;7719.845603087935;7724.150343462215;7638.809029774245;7645.694172233104;7660.5403675779635;7698.230176982313;7703.6918523259;7540.434467414966;7562.168161502231;7579.924200757977;7597.924020759832;7608.581528015957;36659.4501082484;36898.44652330217;36967.445488317426;36971.07572310687;36976.630233697855;2704.283364908301;2706.093458691341;2737.3704048069058;3000.3849210911712;3174.9682503174904;27560.31849967254;27942.301442463897;27977.720222797714;28544.571831422545;31683.366332673217;3073.339232065094;3133.4046531159006;3179.0462861141796;3290.967090329114;3308.503724441353;255939;257868.99999999814;258212;258214.9999999981;259235;28157.71842281592;47565.52434475611;47731.5226847734;47827.521724783;47894.52105478901;29986.25034374158;31624;31730.841345793586;31812;31927.840360798047;11398.544058237578;11726.120540959291;11726.70683232909;11728;11730.70673233176;9196.678116265899;9356.95321523388;9358;9362.06379362072;9364.812703746024;7716.344110750649;7746.0704715433385;7812.375009999187;7828.334591559667;7846.764597062097;3011.909642710722;3027.2431892026993;3040.802347847383;3091.5980922480385;3134.73354764843;827.1397746343855;845.361751877341;874.7332063720627;924.9871390827034;960.0735290444615;26883.596746048825;27570.86214568914;27925.581116283272;27952.161435156977;28069.438611227662;893.7765558610347;900.4826866729582;943;955.5843208204453;1004.6533945788691;13402;14090;14205.644858878612;14437.35031923566;14479.493217737328;369952;372167;372605;373546;373738;46140.7692961533;46582;46681.29978050332;46689.066218675434;46771;1018.4653057145058;1075.4891426572628;1076.5478499030582;1113.5156207049959;1134.659602119361;26478.602820957316;28263.858680707286;30230;31141;31814.840925795223;44883.55116448794;45774.48240346813;45812;45812.31281530846;45815.77092114518;286068;287751;288266;288790;289306;22383.552328953003;23312.76687233106;23323.766762332158;24248.636270455605;24249.75750242475;35718.74921888711;35818.28363432769;38018.429723553614;38140;39694.404583930685;32532.674673252965;32816.34367312593;33163.17092072718;33167.33665326728;33464.49803253002;32589.348213035133;33004.66995330112;33218.33563328768;33868.49197262093;34245.65754342425;29130.854345728134;29526.557101643062;32846.53650976138;33033;33177.17057073592;26751.86624066867;32938.341233174724;33203.66796332102;33249.833750831094;33335.33329333351;24748.628770568095;24768.752312476645;24841.378965526008;25575;25639.230823075337 11:00:40 INFO - PROCESS | 11498 | |2;jquery.html;323.11949936423235;341.06888195227015;342.1224559006149;345.71305816172577;365.92681463707265;11867.940660296697;12943.482260709561;15399.769003464957;15743.291551880164;16830.821842471032;16391.83608163921;16466.588335291624;16508.25712842922;16707.248173832177;16742.58143546412;4371.781410929457;4805.327254184417;5028.522290382408;5033.194688849785;5035.496450354954;5697.202391665171;5878.941210587904;5952.255968003999;5958.255218097738;5980.581359304851;4942.308076869242;5179.611529135311;5925.34821169671;5931.347551769323;5938.88122237555;5211.062008838408;5240.082985477564;5240.816571419982;5246.711430871301;5247.895042099137;4965.280034394992;5102.464241254652;5104.157813960692;5110.7955681773055;5169.457206993249;11543.191976561644;11725.355105469196;11743.765124697544;11760.529578816837;13758.830499407562;1447.0521808215635;1580.2098950524737;1658.1792012953665;1658.946568928727;1775.8224177582267;8513.659453621847;8744.344174186897;8908.599113039929;9119.270458363315;9141.680041198593;1717.9484615461556;1734.332282071399;1735.158448152645;1740.4343588333716;1744.799348074975;65726.67136664285;69787.65106174437;69956;70258.64870675665;71916.64041679811;9810.65662701802;9888.456134912505;9892.901070989343;10096.293259471766;10135.847962280572;13663.316834158253;13668.384922678504;13670;13670.453181872614;13688.65778355549;6403.135576697086;6726.6636668167375;6757.966210168917;6893.276205998398;6896.965515172392;5986.431289027535;6039.365866584033;6194.659293738888;6204.255489341218;6213.875722485525;4058.350663893822;4140.565240649748;4246.320588705792;4272.914541709149;4389.144116897238;1722.8621710263149;1749.212854215608;1768.294509400619;1791.6506281275288;1803.1074618064142;800.8238187598719;804.8932717513419;858.8153546987428;871.8299931513294;933.6452148183647;15774.921125394529;16265.674686506203;16899.408520701716;17116;17167.656646867243;746.4438992950287;774.8411575626983;815;823.6911158315631;868.7567481105301;8682;8815.603297851612;9329.860052099224;9391.34260601765;9573.18627916635;179801;181397;184350;370260;374473;49164.75417622889;69725;72855;85953.57023214844;90130;903.40826758472;911.266430523435;957.167264479907;958.5351104714208;961.0821665309612;9355.391899526508;9523.952380238054;9710.22318214527;14850.925745371636;15099.54701358961;37366.626333737404;37618;37908.620913791616;38231.426528601536;39314.41028384634;48551.75724121357;50997;51733.7413312931;52654;53184.4681553195;6269.529785266032;6431.742730290736;7475.36459400957;8378.329733621174;8408.83182336362;11539.47597601327;11543.883151549202;11960;12137.271763694207;12137.878621213675;9756.51217439111;9963.900360996297;10070.295079344376;10508.21188410857;10757.784844303227;4960.70235785854;5834.591578589458;6184.474319682878;10220.182385409298;10532.947335263274;10123.341982771391;10214.387136771817;10425.635102771366;10533.894661053599;10905.291156074829;10601.310914790514;10634.202434817575;10655.14758819277;10808.297460665031;10876.619318324138;9861.309708320348;10130.696079117639;10204.897951020395;10212.183025358103;10519.842202367123 11:00:40 INFO - PROCESS | 11498 | |3;mootools.html;1196.83842681238;1297.3513243378313;1330.7937269723193;1356.5930220933717;1622.310518029837;6179.505639548836;7381.372583330417;7604.7338343157935;7686.346660533847;7920.405969552292;7782.377409807216;7803.258690424401;7827.373810095193;7847.058352997648;7850.725224617139;2131.26471367378;2157.072458842697;2159.4601349662585;2160.0603737374213;2160.945976350588;2116.3756691775884;2156.8598041127275;2163.1131236193164;2163.4375062483787;2169.761326254111;2132.1471411435473;2136.081484961466;2158.136745301876;2158.9352319430363;2175.673648952654;2129.244118337991;2134.978650213501;2137;2138.754043285011;2138.8075073243326;2039.8980050997538;2089.341857314941;2090.4982804127053;2090.8536402451837;2091.5398612305266;7609.429292803006;7761.3402860756905;7762.107357653886;7764.107127680332;7768.067831860157;4071.4503542021744;4215.725977811432;4232.4286221360035;4253.532111467736;4253.638440732541;2700;2801.985990070057;2848.7293707098;2850.942981140386;2861.2131663792416;951.1819834941945;951.2770294576172;960.6301573894032;971.6550624528261;990.9653162139291;10226.693199204035;10280;10287.537060832281;10322.690319290434;10324.896751032544;3747.868824591126;3870.4774855394726;3890.124721937598;3913.2369188008065;4119.485064366954;8110.3511719062335;8238.052623948202;8257.05043919945;8259.587020648944;8261.215184557457;4273.209456250542;4277;4279.40088387626;4279.529251782302;4281.400603915455;3373.4265174920324;3495.580530336325;3790.753601015925;4008.478897743333;4012.598740126022;3267.6568960258833;3305.86776528941;3382.5433566468705;3400.1329660936303;3434.8797792077658;988.051470588229;995.4723996281982;997.0328781082338;1004.8040632076675;1009.8131845608586;671.6977360187935;681.9838440723287;695.266493848984;725.8536972862431;752.6575408189261;4974.552290293891;5085.567726743194;5145.536901678792;5198.454162312903;5258.579313654898;385.3564547206116;395.6261333040307;396.9702272329529;397.5368695469803;399.8480577380635;3446.5347178131133;3461.1001139703353;3585.229175727231;3602.621724718867;3643.0327623083767;9191.816163676824;9585.137337639506;9648.951755241178;9649.758756031015;9657.420554766737;9288.303377246733;9513.381630194153;9547.236221102296;9552.95223523878;9580.904190958001;7129.536580122275;7346.08173978242;7352.375048120863;7359.233783891827;7366.889496657554;4990.575801056951;5046.968699088374;5070.797168113234;5104.259882316969;5171.560417364566;8799.736007919517;8855.114488551351;8857.557122143739;8862.556872156238;8908.10918908104;8695.21743043104;8973.102689730975;8981;8989.235914947041;9073.818523629623;6111.633302001894;6124.510039196943;6176.135341052166;6177.536684748752;6180;7082.575045497287;7140.357367836919;7185.101862267216;7201.819954501179;7226.349628533457;6340.334264902119;6350.079238510298;6354.332795056637;6412.583182093149;6489.026646002949;6271.247450305992;6304.338044505261;6367.108604795425;6392.616443013248;6404.231492220963;6142.78500252489;6175.62946223228;6181.443670069715;6186.072089186658;6186.381361863778;4470.530594287553;4494.850684754551;4564.8402305919135;4571.725696458223;4700.55344742249;4469.150861336336;4500.707454015478;4545.8181672734045;4554.658400619901;4655.464621568563 11:00:40 INFO - PROCESS | 11498 | |4;prototype.html;289.4803827130301;290.5409453064159;293.8986049812815;295.9082684367846;296.2829951517328;4826.855194344166;4827.541383568555;4832.9275060874115;4837.637177211705;4845.4427740809815;4754.215554433523;4771.928421073678;4791.059620853551;4794.83218087367;4833.3958255218095;3532.41715117006;3644.653757892996;3683.852645894174;3738.8317525711273;3759.7556158849725;3760.830762615675;3762.9811850940705;3767.4348847672795;3767.4537192107127;3769.32152212603;3723.5717892442444;3731.4589384539227;3740.4763333133383;3744.3634582121103;3761.2665530221616;3558.1104723819176;3632.964605087537;3648.689861361761;3736.178040831013;3745.9063523411996;3280.5729507664005;3284.3431313737346;3289.7697161198726;3290.5393244945726;3291.3417316536547;4662.3705799716945;4663.160631086403;4679.81280748773;4689.601383882374;4693.9530604693855;4145.834166633331;4260.829566817323;4271.295236286009;4275.679324050709;4276.508201556799;4934.333864928225;4969.279454479117;4975.502449755032;4976.751162441863;4983.327250821165;3581.7313701472494;3589.210373717804;3592.4072528032843;3593.389123848926;3595.568531776177;5091;5199.558037566811;5204.037253108149;5224.451432599561;5259.158534634478;3626.492291079251;3634.7273954452994;3636.70896831621;3639.4722765199426;3640.0535860676414;3684.6868016218923;3687.760295580779;3691.778493290411;3694.685951694082;3695.963040369562;4231.555686652919;4233.534311225762;4239.766812825324;4240.978795106004;4246.065865509583;4936.71826625387;4978.77595508203;4995.25071239317;5078.7460626968505;5083.237514372874;1269.4541347220784;1287.8068289756611;1288.7937929931165;1294.346355090692;1296.9481220751065;805.9556724380216;829.2992421403904;830.9002919649681;836.9246767790928;837.0959363886988;4110.77390743506;4310.547392523803;4380.69335146543;4442.244818380883;4516.9159401744;5135;5140.151874940668;5169.896602067937;5188.818391356284;5191.454897235812;4514.819407223675;4520.367148599199;4524.819007239739;4526.23054080807;4529.773511324421;2605.918543804338;2714.891404343804;2717.442924200534;2735;2751.3259251483514;4888.2178851383605;4889.535494128052;4890.413150421901;4894.951050489521;4895.559399654048;5279.287296215039;5288.9735551322;5295.549878260391;5297.17893726473;5303.04545181871;4715.599174070174;4873.756312184448;4875.293082502946;4883.658143929891;4885.877853053702;5147.893202961382;5151.150060240168;5154.561862241751;5161.174212126042;5164.690118592896;4858.17411040117;5094.617903657315;5095.108356037753;5096.821611243737;5104.9489505104475;4857.975710121426;4861.149298872612;4865.6107511398295;4880.999395124062;4882.7314497701545;3695.3348397288746;3719.1260053887854;3725.1245957200576;3747.7376583638884;3760.229153023569;5175.560077393464;5191.922121168261;5198.194279886624;5201.167813150029;5224.268602395591;4814.975925120352;4974.37820272466;4985.501449855131;4989.725565093955;5001.449840517613;4953.851384458472;4955.4301255356095;4977.328060711902;4982.128127577587;5008.298838162587;4902.460729319843;4908.662109765394;4951.801927922843;4960.330355401975;4961.900761984813;4794.184988551885;4822.300766388784;4931.334269873521;5042.193249080129;5062;4831.849826827169;4937.7531123444405;4943.901121977468;4947.208446648519;4957.801687932587 11:00:40 INFO - PROCESS | 11498 | |5;yui.html;566.7846218436995;596.4184919703287;632.5192853431392;633.518525920301;659.0871642774756;11984.76030479391;12597;13041.152325098861;13166.473341066358;13258.535951241709;11810;12099.213551119194;12108.212966157194;12113.515459381613;12122.45448954797;588.8645611509355;719.5538765965109;738.1806195123409;740.7703611880307;741.1106671993603;598.0849300570113;724.8586525627504;743.8028922335585;748.7641392961227;752.7741677496756;790.7904405332591;791.8257983243677;794.2018271636997;796.4424902568196;796.4862663581986;612.6936531734088;789.8736202207676;790.3479629305817;790.6797746912509;790.8576456237846;460.59467668451884;551.3576683164109;566.4250785452768;568.6247076929207;571.4685342631351;12494.625161245178;12518.687032824162;12537.247765134121;12541.059420543495;12546.623601291976;12237;12443.813342799865;13173.934130329382;13410.46358145673;13725.45098196071;11531;11764.235324703866;11792.23350482216;11936.880631193664;12481.937590312082;3943.4676318696897;4000.4199391088127;4082.966513336732;4084.1657895259564;4131.277026520347;66457.3354266456;66670.66664666694;66790.3320966789;66869;67698;10119.24105692076;10267.178625709925;10397.896021039693;10557.524911379007;10686;18472.353467628567;18520.81479185191;18598.907005464887;18654.53363665892;18897.244110235435;6355.841103972438;6407.7757278495965;6407.839804004937;6418.77534286307;6597.175353080865;3579.3557159711504;3606.74752767309;3620.4569314602495;3632.3643362412;3637.1270894985014;2509.6737424134744;2593.2090712332542;2598.2854714953537;3063.8161710297454;3223.7098661120613;501.3299581730638;502.18395107949584;508.7278306106289;529.9099153143975;540.5648452995298;415.81911868336766;487.24720307125574;496.2184559319087;497.3186734174132;513.7200225876906;7552.207018263113;8116.228958248957;8146.103928567972;8248.381371397048;8336.8332633347;486.96347773917097;489.06344350568975;490.7742438478269;490.8502906613517;504.19832466378637;3647.562292524861;3674.5039419678537;3849.557300910431;3852.653261206449;3897.8830635080994;58035;58045.41954580485;58171;59143.40856591465;89881;86930;87883.56058219796;88786.01079389433;89328;92659.53670231605;80975.59512202519;82387;82722.58638706768;83231;83669.58165209135;7203.891941620985;7427.145878224073;7464.328210461084;7634.618269086634;7638.274363935418;46851.53148468472;47158.29262560996;48199.75900120477;49104;49121.75439122781;60187;62014.689926550076;67090.66454667696;68062.3193768056;70040;9210.861837072584;9244.95377523108;9332.327385918828;9498.382605130644;9606;9420.764480888032;9802.803943921224;9837.697899034267;10088.747781305527;10252.538635761259;9763.658271960447;10080.395176289447;10268.845967310346;11477.770444591228;11806.704832379259;11708.70728231767;11929.701757456132;12389.43354032302;12573.685657858627;13033.674158146123;11129.499172537093;11366.261193022427;11562.248453850472;12152.696182595506;12402.441890115148;9362.719118426457;9429.575669095044;9605.423674579548;10192.337498062601;10267.7433064174;8883.554612138025;9234.261259099121;10030.949845250727;10098.899011009797;10146.391216527032 11:00:40 INFO - PROCESS | 11498 | __end_tp_report 11:00:40 INFO - PROCESS | 11498 | __start_cc_report 11:00:40 INFO - PROCESS | 11498 | _x_x_mozilla_cycle_collect,1050 11:00:40 INFO - PROCESS | 11498 | __end_cc_report 11:00:40 INFO - PROCESS | 11498 | __startTimestamp1474135240496__endTimestamp 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | ------- Summary: start ------- 11:00:40 INFO - PROCESS | 11498 | Number of tests: 6 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#0] dojo.html Cycles:165 Average:4676.57 Median:4832.98 stddev:493.24 (10.2%) stddev-sans-first:466.88 11:00:40 INFO - PROCESS | 11498 | Values: 2592.6 2621.9 2651.5 2659.7 2676.4 4554.3 4622.5 4674.0 4758.7 4822.2 4651.1 4670.9 4674.9 4681.6 4685.8 4285.7 4377.1 4398.1 4446.6 4458.8 4369.8 4406.6 4436.2 4456.6 4485.7 4395.5 4453.4 4487.3 4512.4 4516.9 4418.3 4439.1 4471.7 4510.5 4524.2 4151.4 4180.4 4183.1 4241.9 4347.6 4630.6 4666.2 4675.1 4744.3 4757.5 4958.4 4966.2 4967.5 4990.2 5043.9 4921.6 4973.9 5004.9 5012.7 5057.6 4742.2 4781.6 4786.2 4847.6 4849.8 5244.5 5245.5 5249.2 5250.3 5305.1 5011.0 5014.5 5015.2 5019.2 5073.4 4955.4 5008.2 5016.4 5073.9 5098.4 4940.7 4964.7 4998.1 5016.4 5062.0 4935.0 4982.1 4984.1 5025.3 5040.0 4199.4 4200.1 4214.6 4255.7 4268.2 3647.4 3663.0 3672.2 3702.0 3745.3 4306.3 4359.1 4376.7 4379.5 4381.3 5045.3 5047.4 5081.2 5095.9 5109.9 4370.7 4380.1 4381.1 4440.8 4441.5 4382.8 4386.1 4496.2 4506.3 4514.6 4807.7 4821.7 4829.3 4887.1 4889.7 5072.8 5096.8 5112.6 5140.5 5176.5 4705.6 4752.8 4819.5 4833.0 4885.5 4960.2 5011.6 5012.0 5016.3 5070.5 5059.4 5068.3 5075.9 5100.5 5131.5 4806.1 4920.0 4938.9 4939.5 4991.9 4834.8 4852.1 4882.3 4932.8 5004.7 4856.0 4861.4 4863.0 4873.4 4920.9 4913.6 4918.9 4940.9 4954.1 4968.9 4744.3 4785.6 4867.4 4899.4 4915.6 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#1] ext.html Cycles:180 Average:44147.14 Median:33292.58 stddev:81747.93 (245.5%) stddev-sans-first:81911.76 11:00:40 INFO - PROCESS | 11498 | Values: 563.4 576.2 577.8 585.0 586.6 26886.5 27612.2 29224.7 36959.0 38033.6 38786.8 39165.4 39191.8 39211.6 39227.2 7555.0 7705.4 7706.0 7707.1 7710.1 7596.3 7679.4 7680.4 7680.6 7684.2 7671.6 7711.8 7718.8 7719.8 7724.2 7638.8 7645.7 7660.5 7698.2 7703.7 7540.4 7562.2 7579.9 7597.9 7608.6 36659.5 36898.4 36967.4 36971.1 36976.6 2704.3 2706.1 2737.4 3000.4 3175.0 27560.3 27942.3 27977.7 28544.6 31683.4 3073.3 3133.4 3179.0 3291.0 3308.5 255939.0 257869.0 258212.0 258215.0 259235.0 28157.7 47565.5 47731.5 47827.5 47894.5 29986.3 31624.0 31730.8 31812.0 31927.8 11398.5 11726.1 11726.7 11728.0 11730.7 9196.7 9357.0 9358.0 9362.1 9364.8 7716.3 7746.1 7812.4 7828.3 7846.8 3011.9 3027.2 3040.8 3091.6 3134.7 827.1 845.4 874.7 925.0 960.1 26883.6 27570.9 27925.6 27952.2 28069.4 893.8 900.5 943.0 955.6 1004.7 13402.0 14090.0 14205.6 14437.4 14479.5 369952.0 372167.0 372605.0 373546.0 373738.0 46140.8 46582.0 46681.3 46689.1 46771.0 1018.5 1075.5 1076.5 1113.5 1134.7 26478.6 28263.9 30230.0 31141.0 31814.8 44883.6 45774.5 45812.0 45812.3 45815.8 286068.0 287751.0 288266.0 288790.0 289306.0 22383.6 23312.8 23323.8 24248.6 24249.8 35718.7 35818.3 38018.4 38140.0 39694.4 32532.7 32816.3 33163.2 33167.3 33464.5 32589.3 33004.7 33218.3 33868.5 34245.7 29130.9 29526.6 32846.5 33033.0 33177.2 26751.9 32938.3 33203.7 33249.8 33335.3 24748.6 24768.8 24841.4 25575.0 25639.2 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#2] jquery.html Cycles:180 Average:20113.63 Median:48858.26 stddev:46603.65 (95.4%) stddev-sans-first:46710.70 11:00:40 INFO - PROCESS | 11498 | Values: 323.1 341.1 342.1 345.7 365.9 11867.9 12943.5 15399.8 15743.3 16830.8 16391.8 16466.6 16508.3 16707.2 16742.6 4371.8 4805.3 5028.5 5033.2 5035.5 5697.2 5878.9 5952.3 5958.3 5980.6 4942.3 5179.6 5925.3 5931.3 5938.9 5211.1 5240.1 5240.8 5246.7 5247.9 4965.3 5102.5 5104.2 5110.8 5169.5 11543.2 11725.4 11743.8 11760.5 13758.8 1447.1 1580.2 1658.2 1658.9 1775.8 8513.7 8744.3 8908.6 9119.3 9141.7 1717.9 1734.3 1735.2 1740.4 1744.8 65726.7 69787.7 69956.0 70258.6 71916.6 9810.7 9888.5 9892.9 10096.3 10135.8 13663.3 13668.4 13670.0 13670.5 13688.7 6403.1 6726.7 6758.0 6893.3 6897.0 5986.4 6039.4 6194.7 6204.3 6213.9 4058.4 4140.6 4246.3 4272.9 4389.1 1722.9 1749.2 1768.3 1791.7 1803.1 800.8 804.9 858.8 871.8 933.6 15774.9 16265.7 16899.4 17116.0 17167.7 746.4 774.8 815.0 823.7 868.8 8682.0 8815.6 9329.9 9391.3 9573.2 179801.0 181397.0 184350.0 370260.0 374473.0 49164.8 69725.0 72855.0 85953.6 90130.0 903.4 911.3 957.2 958.5 961.1 9355.4 9524.0 9710.2 14850.9 15099.5 37366.6 37618.0 37908.6 38231.4 39314.4 48551.8 50997.0 51733.7 52654.0 53184.5 6269.5 6431.7 7475.4 8378.3 8408.8 11539.5 11543.9 11960.0 12137.3 12137.9 9756.5 9963.9 10070.3 10508.2 10757.8 4960.7 5834.6 6184.5 10220.2 10532.9 10123.3 10214.4 10425.6 10533.9 10905.3 10601.3 10634.2 10655.1 10808.3 10876.6 9861.3 10130.7 10204.9 10212.2 10519.8 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#3] mootools.html Cycles:180 Average:4946.45 Median:5078.18 stddev:2835.48 (55.8%) stddev-sans-first:2829.43 11:00:40 INFO - PROCESS | 11498 | Values: 1196.8 1297.4 1330.8 1356.6 1622.3 6179.5 7381.4 7604.7 7686.3 7920.4 7782.4 7803.3 7827.4 7847.1 7850.7 2131.3 2157.1 2159.5 2160.1 2160.9 2116.4 2156.9 2163.1 2163.4 2169.8 2132.1 2136.1 2158.1 2158.9 2175.7 2129.2 2135.0 2137.0 2138.8 2138.8 2039.9 2089.3 2090.5 2090.9 2091.5 7609.4 7761.3 7762.1 7764.1 7768.1 4071.5 4215.7 4232.4 4253.5 4253.6 2700.0 2802.0 2848.7 2850.9 2861.2 951.2 951.3 960.6 971.7 991.0 10226.7 10280.0 10287.5 10322.7 10324.9 3747.9 3870.5 3890.1 3913.2 4119.5 8110.4 8238.1 8257.1 8259.6 8261.2 4273.2 4277.0 4279.4 4279.5 4281.4 3373.4 3495.6 3790.8 4008.5 4012.6 3267.7 3305.9 3382.5 3400.1 3434.9 988.1 995.5 997.0 1004.8 1009.8 671.7 682.0 695.3 725.9 752.7 4974.6 5085.6 5145.5 5198.5 5258.6 385.4 395.6 397.0 397.5 399.8 3446.5 3461.1 3585.2 3602.6 3643.0 9191.8 9585.1 9649.0 9649.8 9657.4 9288.3 9513.4 9547.2 9553.0 9580.9 7129.5 7346.1 7352.4 7359.2 7366.9 4990.6 5047.0 5070.8 5104.3 5171.6 8799.7 8855.1 8857.6 8862.6 8908.1 8695.2 8973.1 8981.0 8989.2 9073.8 6111.6 6124.5 6176.1 6177.5 6180.0 7082.6 7140.4 7185.1 7201.8 7226.3 6340.3 6350.1 6354.3 6412.6 6489.0 6271.2 6304.3 6367.1 6392.6 6404.2 6142.8 6175.6 6181.4 6186.1 6186.4 4470.5 4494.9 4564.8 4571.7 4700.6 4469.2 4500.7 4545.8 4554.7 4655.5 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#4] prototype.html Cycles:180 Average:4164.40 Median:4804.90 stddev:1211.90 (25.2%) stddev-sans-first:1179.89 11:00:40 INFO - PROCESS | 11498 | Values: 289.5 290.5 293.9 295.9 296.3 4826.9 4827.5 4832.9 4837.6 4845.4 4754.2 4771.9 4791.1 4794.8 4833.4 3532.4 3644.7 3683.9 3738.8 3759.8 3760.8 3763.0 3767.4 3767.5 3769.3 3723.6 3731.5 3740.5 3744.4 3761.3 3558.1 3633.0 3648.7 3736.2 3745.9 3280.6 3284.3 3289.8 3290.5 3291.3 4662.4 4663.2 4679.8 4689.6 4694.0 4145.8 4260.8 4271.3 4275.7 4276.5 4934.3 4969.3 4975.5 4976.8 4983.3 3581.7 3589.2 3592.4 3593.4 3595.6 5091.0 5199.6 5204.0 5224.5 5259.2 3626.5 3634.7 3636.7 3639.5 3640.1 3684.7 3687.8 3691.8 3694.7 3696.0 4231.6 4233.5 4239.8 4241.0 4246.1 4936.7 4978.8 4995.3 5078.7 5083.2 1269.5 1287.8 1288.8 1294.3 1296.9 806.0 829.3 830.9 836.9 837.1 4110.8 4310.5 4380.7 4442.2 4516.9 5135.0 5140.2 5169.9 5188.8 5191.5 4514.8 4520.4 4524.8 4526.2 4529.8 2605.9 2714.9 2717.4 2735.0 2751.3 4888.2 4889.5 4890.4 4895.0 4895.6 5279.3 5289.0 5295.5 5297.2 5303.0 4715.6 4873.8 4875.3 4883.7 4885.9 5147.9 5151.2 5154.6 5161.2 5164.7 4858.2 5094.6 5095.1 5096.8 5104.9 4858.0 4861.1 4865.6 4881.0 4882.7 3695.3 3719.1 3725.1 3747.7 3760.2 5175.6 5191.9 5198.2 5201.2 5224.3 4815.0 4974.4 4985.5 4989.7 5001.4 4953.9 4955.4 4977.3 4982.1 5008.3 4902.5 4908.7 4951.8 4960.3 4961.9 4794.2 4822.3 4931.3 5042.2 5062.0 4831.8 4937.8 4943.9 4947.2 4957.8 11:00:40 INFO - PROCESS | 11498 | 11:00:40 INFO - PROCESS | 11498 | [#5] yui.html Cycles:180 Average:17318.07 Median:501.76 stddev:24581.59 (4899.1%) stddev-sans-first:24618.37 11:00:40 INFO - PROCESS | 11498 | Values: 566.8 596.4 632.5 633.5 659.1 11984.8 12597.0 13041.2 13166.5 13258.5 11810.0 12099.2 12108.2 12113.5 12122.5 588.9 719.6 738.2 740.8 741.1 598.1 724.9 743.8 748.8 752.8 790.8 791.8 794.2 796.4 796.5 612.7 789.9 790.3 790.7 790.9 460.6 551.4 566.4 568.6 571.5 12494.6 12518.7 12537.2 12541.1 12546.6 12237.0 12443.8 13173.9 13410.5 13725.5 11531.0 11764.2 11792.2 11936.9 12481.9 3943.5 4000.4 4083.0 4084.2 4131.3 66457.3 66670.7 66790.3 66869.0 67698.0 10119.2 10267.2 10397.9 10557.5 10686.0 18472.4 18520.8 18598.9 18654.5 18897.2 6355.8 6407.8 6407.8 6418.8 6597.2 3579.4 3606.7 3620.5 3632.4 3637.1 2509.7 2593.2 2598.3 3063.8 3223.7 501.3 502.2 508.7 529.9 540.6 415.8 487.2 496.2 497.3 513.7 7552.2 8116.2 8146.1 8248.4 8336.8 487.0 489.1 490.8 490.9 504.2 3647.6 3674.5 3849.6 3852.7 3897.9 58035.0 58045.4 58171.0 59143.4 89881.0 86930.0 87883.6 88786.0 89328.0 92659.5 80975.6 82387.0 82722.6 83231.0 83669.6 7203.9 7427.1 7464.3 7634.6 7638.3 46851.5 47158.3 48199.8 49104.0 49121.8 60187.0 62014.7 67090.7 68062.3 70040.0 9210.9 9245.0 9332.3 9498.4 9606.0 9420.8 9802.8 9837.7 10088.7 10252.5 9763.7 10080.4 10268.8 11477.8 11806.7 11708.7 11929.7 12389.4 12573.7 13033.7 11129.5 11366.3 11562.2 12152.7 12402.4 9362.7 9429.6 9605.4 10192.3 10267.7 8883.6 9234.3 10030.9 10098.9 10146.4 11:00:40 INFO - PROCESS | 11498 | -------- Summary: end -------- 11:00:40 INFO - PROCESS | 11498 | 11:00:41 INFO - TEST-INFO | 11498: exit 0 11:00:41 INFO - TEST-OK | dromaeo_css | took 1121312ms 11:00:41 INFO - TEST-START | kraken 11:00:41 INFO - operating with platform_type : linux_ 11:00:41 INFO - Initialising browser for kraken test... 11:00:41 INFO - TEST-INFO | started process 11804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq24n2Q/profile http://localhost:47258/getInfo.html) 11:00:45 INFO - PROCESS | 11804 | __metrics Screen width/height:1600/1200 11:00:45 INFO - PROCESS | 11804 | colorDepth:24 11:00:45 INFO - PROCESS | 11804 | Browser inner width/height: 1024/697 11:00:45 INFO - PROCESS | 11804 | __metrics 11:00:46 INFO - PROCESS | 11804 | 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" 11:00:46 INFO - PROCESS | 11804 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:00:46 INFO - PROCESS | 11804 | console.error: PushService: 11:00:46 INFO - PROCESS | 11804 | stateChangeProcessEnqueue: Error transitioning state 11:00:46 INFO - PROCESS | 11804 | UnknownError 11:00:47 INFO - TEST-INFO | 11804: exit 0 11:00:47 INFO - Browser initialized. 11:00:47 INFO - Running cycle 1/1 for kraken test... 11:00:47 INFO - TEST-INFO | started process 11887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq24n2Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:00:59 INFO - PROCESS | 11887 | RSS: Main: 174620672 11:00:59 INFO - PROCESS | 11887 | 11:01:02 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47258/tests/kraken/driver.html?testName=audio-beat-detection) 11:01:03 INFO - PROCESS | 11887 | RSS: Main: 232919040 11:01:03 INFO - PROCESS | 11887 | 11:01:06 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47258/tests/kraken/driver.html?testName=audio-dft) 11:01:06 INFO - PROCESS | 11887 | RSS: Main: 217464832 11:01:06 INFO - PROCESS | 11887 | 11:01:10 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47258/tests/kraken/driver.html?testName=audio-fft) 11:01:10 INFO - PROCESS | 11887 | RSS: Main: 299540480 11:01:10 INFO - PROCESS | 11887 | 11:01:12 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47258/tests/kraken/driver.html?testName=audio-oscillator) 11:01:13 INFO - PROCESS | 11887 | RSS: Main: 209047552 11:01:13 INFO - PROCESS | 11887 | 11:01:15 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:01:16 INFO - PROCESS | 11887 | RSS: Main: 213221376 11:01:16 INFO - PROCESS | 11887 | 11:01:19 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47258/tests/kraken/driver.html?testName=imaging-darkroom) 11:01:20 INFO - PROCESS | 11887 | RSS: Main: 249352192 11:01:20 INFO - PROCESS | 11887 | 11:01:23 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47258/tests/kraken/driver.html?testName=imaging-desaturate) 11:01:24 INFO - PROCESS | 11887 | RSS: Main: 243908608 11:01:24 INFO - PROCESS | 11887 | 11:01:27 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47258/tests/kraken/driver.html?testName=json-parse-financial) 11:01:27 INFO - PROCESS | 11887 | RSS: Main: 246599680 11:01:27 INFO - PROCESS | 11887 | 11:01:29 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:01:29 INFO - PROCESS | 11887 | RSS: Main: 209989632 11:01:29 INFO - PROCESS | 11887 | 11:01:31 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:01:32 INFO - PROCESS | 11887 | RSS: Main: 217427968 11:01:32 INFO - PROCESS | 11887 | 11:01:34 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:01:35 INFO - PROCESS | 11887 | RSS: Main: 222216192 11:01:35 INFO - PROCESS | 11887 | 11:01:38 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:01:38 INFO - PROCESS | 11887 | RSS: Main: 223301632 11:01:38 INFO - PROCESS | 11887 | 11:01:41 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:01:42 INFO - PROCESS | 11887 | RSS: Main: 221126656 11:01:42 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | Cycle 1(1): loaded http://localhost:47258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47258/tests/kraken/driver.html?testName=ai-astar) 11:01:44 INFO - PROCESS | 11887 | RSS: Main: 272429056 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | __start_tp_report 11:01:44 INFO - PROCESS | 11887 | _x_x_mozilla_page_load 11:01:44 INFO - PROCESS | 11887 | _x_x_mozilla_page_load_details 11:01:44 INFO - PROCESS | 11887 | |i|pagename|runs| 11:01:44 INFO - PROCESS | 11887 | |0;ai-astar;111;117;115;114;115;114;115;115;113;115 11:01:44 INFO - PROCESS | 11887 | |1;audio-beat-detection;155;124;123;164;128;128;126;126;160;126 11:01:44 INFO - PROCESS | 11887 | |2;audio-dft;162;162;161;162;161;162;161;162;161;159 11:01:44 INFO - PROCESS | 11887 | |3;audio-fft;79;77;77;121;78;78;78;76;101;75 11:01:44 INFO - PROCESS | 11887 | |4;audio-oscillator;84;84;135;86;85;83;120;83;84;112 11:01:44 INFO - PROCESS | 11887 | |5;imaging-gaussian-blur;116;117;116;117;117;116;116;116;117;116 11:01:44 INFO - PROCESS | 11887 | |6;imaging-darkroom;146;147;146;146;146;146;147;146;145;146 11:01:44 INFO - PROCESS | 11887 | |7;imaging-desaturate;95;95;95;95;95;95;95;97;95;95 11:01:44 INFO - PROCESS | 11887 | |8;json-parse-financial;64;63;64;63;64;67;65;65;65;66 11:01:44 INFO - PROCESS | 11887 | |9;json-stringify-tinderbox;65;66;65;67;66;66;66;66;67;66 11:01:44 INFO - PROCESS | 11887 | |10;stanford-crypto-aes;82;81;84;82;88;81;82;85;81;87 11:01:44 INFO - PROCESS | 11887 | |11;stanford-crypto-ccm;134;142;142;144;148;132;135;141;171;144 11:01:44 INFO - PROCESS | 11887 | |12;stanford-crypto-pbkdf2;171;173;174;175;172;176;171;177;171;172 11:01:44 INFO - PROCESS | 11887 | |13;stanford-crypto-sha256-iterative;69;69;71;72;69;69;68;69;69;68 11:01:44 INFO - PROCESS | 11887 | __end_tp_report 11:01:44 INFO - PROCESS | 11887 | __start_cc_report 11:01:44 INFO - PROCESS | 11887 | _x_x_mozilla_cycle_collect,1936 11:01:44 INFO - PROCESS | 11887 | __end_cc_report 11:01:44 INFO - PROCESS | 11887 | __startTimestamp1474135304395__endTimestamp 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | ------- Summary: start ------- 11:01:44 INFO - PROCESS | 11887 | Number of tests: 14 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:1.58 (1.4%) stddev-sans-first:1.09 11:01:44 INFO - PROCESS | 11887 | Values: 111.0 117.0 115.0 114.0 115.0 114.0 115.0 115.0 113.0 115.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:128.00 stddev:16.54 (12.9%) stddev-sans-first:16.05 11:01:44 INFO - PROCESS | 11887 | Values: 155.0 124.0 123.0 164.0 128.0 128.0 126.0 126.0 160.0 126.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 11:01:44 INFO - PROCESS | 11887 | Values: 162.0 162.0 161.0 162.0 161.0 162.0 161.0 162.0 161.0 159.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#3] audio-fft Cycles:10 Average:84.00 Median:77.50 stddev:15.03 (19.4%) stddev-sans-first:15.84 11:01:44 INFO - PROCESS | 11887 | Values: 79.0 77.0 77.0 121.0 78.0 78.0 78.0 76.0 101.0 75.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:19.27 (22.9%) stddev-sans-first:19.98 11:01:44 INFO - PROCESS | 11887 | Values: 84.0 84.0 135.0 86.0 85.0 83.0 120.0 83.0 84.0 112.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 11:01:44 INFO - PROCESS | 11887 | Values: 116.0 117.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 11:01:44 INFO - PROCESS | 11887 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 146.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:01:44 INFO - PROCESS | 11887 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 11:01:44 INFO - PROCESS | 11887 | Values: 64.0 63.0 64.0 63.0 64.0 67.0 65.0 65.0 65.0 66.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 11:01:44 INFO - PROCESS | 11887 | Values: 65.0 66.0 65.0 67.0 66.0 66.0 66.0 66.0 67.0 66.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.00 stddev:2.58 (3.1%) stddev-sans-first:2.70 11:01:44 INFO - PROCESS | 11887 | Values: 82.0 81.0 84.0 82.0 88.0 81.0 82.0 85.0 81.0 87.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:143.00 stddev:10.97 (7.7%) stddev-sans-first:11.10 11:01:44 INFO - PROCESS | 11887 | Values: 134.0 142.0 142.0 144.0 148.0 132.0 135.0 141.0 171.0 144.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:173.50 stddev:2.20 (1.3%) stddev-sans-first:2.19 11:01:44 INFO - PROCESS | 11887 | Values: 171.0 173.0 174.0 175.0 172.0 176.0 171.0 177.0 171.0 172.0 11:01:44 INFO - PROCESS | 11887 | 11:01:44 INFO - PROCESS | 11887 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.25 (1.8%) stddev-sans-first:1.32 11:01:44 INFO - PROCESS | 11887 | Values: 69.0 69.0 71.0 72.0 69.0 69.0 68.0 69.0 69.0 68.0 11:01:44 INFO - PROCESS | 11887 | -------- Summary: end -------- 11:01:44 INFO - PROCESS | 11887 | 11:01:45 INFO - TEST-INFO | 11887: exit 0 11:01:45 INFO - TEST-OK | kraken | took 64200ms 11:01:45 INFO - SUITE-END | took 1185s 11:01:46 INFO - Completed test suite (00:19:45) 11:01:46 INFO - javascript benchmark 11:01:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.5851863701805, 2621.868906554672, 2651.5094707479116, 2659.6542449481562, 2676.371052802591, 4554.339620754997, 4622.491525932145, 4674.029637280997, 4758.666893317465, 4822.180229361008, 4651.069786042796, 4670.859874203771, 4674.906501869969, 4681.555252251031, 4685.789139488723, 4285.700000999924, 4377.080813029268, 4398.098389830077, 4446.55534446556, 4458.843940462085, 4369.80335884886, 4406.647468202536, 4436.179306828231, 4456.5766252205985, 4485.663575231854, 4395.494518130408, 4453.354263631788, 4487.349334346518, 4512.413386259782, 4516.864494065183, 4418.315161150027, 4439.1121775645, 4471.664625153094, 4510.526394728556, 4524.2082635538645, 4151.418801367811, 4180.435641188431, 4183.100633363842, 4241.893952651209, 4347.630451411634, 4630.606398456135, 4666.160091183586, 4675.135100006476, 4744.335792988984, 4757.548032936866, 4958.355413796184, 4966.230234313651, 4967.528084831935, 4990.2265148901615, 5043.949560504385, 4921.581665558432, 4973.875653108665, 5004.874878128039, 5012.749362531858, 5057.645964782467, 4742.241241401359, 4781.617470602343, 4786.210275304606, 4847.587955023862, 4849.80600775965, 5244.501772331622, 5245.527902488794, 5249.186376111708, 5250.317458730341, 5305.124654431976, 5011.022850544182, 5014.498550144956, 5015.247712843103, 5019.247112933089, 5073.391193056783, 4955.35580374549, 5008.223725322581, 5016.372953380827, 5073.923891141636, 5098.388193416814, 4940.728259945738, 4964.702117872939, 4998.075356059141, 5016.423111342169, 5061.974690126526, 4934.9753251233515, 4982.053409852189, 4984.102861484895, 5025.296458495814, 5040.042391945519, 4199.391088292181, 4200.1179752252365, 4214.593550304628, 4255.744655320691, 4268.16770729711, 3647.379945409287, 3662.981685091611, 3672.210474747942, 3702.0, 3745.2509498099944, 4306.267934451151, 4359.106383191438, 4376.671749618791, 4379.496357918816, 4381.255186618282, 5045.293658887758, 5047.394312682502, 5081.161608334657, 5095.923561146586, 5109.897802043938, 4370.7159034662645, 4380.123975204971, 4381.145676593097, 4440.822367105279, 4441.467023957081, 4382.824687012484, 4386.144701783185, 4496.1906856765445, 4506.3465797459185, 4514.593686568159, 4807.663463557517, 4821.686590371614, 4829.251466022632, 4887.095887260726, 4889.706617602955, 5072.771725272445, 5096.770645320896, 5112.6165537585575, 5140.46025167367, 5176.534111929945, 4705.55297246761, 4752.833650822205, 4819.493953135011, 4832.975835120943, 4885.5358740919555, 4960.206366981266, 5011.649184557046, 5011.997600479992, 5016.297718319511, 5070.467600901852, 5059.418166910852, 5068.341115654941, 5075.898482030412, 5100.5409513143995, 5131.512506311894, 4806.134895718665, 4920.0, 4938.925916111333, 4939.506049395031, 4991.900161996812, 4834.806607735652, 4852.078105160008, 4882.292067650242, 4932.778024988812, 5004.699718016931, 4856.000118946677, 4861.392325959255, 4863.027394521039, 4873.439554451142, 4920.876978075434, 4913.6069114471475, 4918.901621967469, 4940.901181976268, 4954.083494553519, 4968.8757781055765, 4744.288356746378, 4785.59322457581, 4867.440244371801, 4899.387576552931, 4915.582175515121], "value": 4644.812676290312, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.4140493886358, 576.2019602850052, 577.7862190989333, 584.9970750146251, 586.551288264478, 26886.46227075457, 27612.238776122376, 29224.70775292249, 36959.0, 38033.61966380335, 38786.836394908125, 39165.41251881224, 39191.804040979754, 39211.607883921155, 39227.21545569094, 7554.96222518888, 7705.383569314441, 7705.961470192641, 7707.113681926594, 7710.074791025085, 7596.278353556431, 7679.424043196755, 7680.423968202379, 7680.615969201545, 7684.193159718233, 7671.578063206522, 7711.845763084735, 7718.768436946901, 7719.845603087935, 7724.150343462215, 7638.809029774245, 7645.694172233104, 7660.5403675779635, 7698.230176982313, 7703.6918523259, 7540.434467414966, 7562.168161502231, 7579.924200757977, 7597.924020759832, 7608.581528015957, 36659.4501082484, 36898.44652330217, 36967.445488317426, 36971.07572310687, 36976.630233697855, 2704.283364908301, 2706.093458691341, 2737.3704048069058, 3000.3849210911712, 3174.9682503174904, 27560.31849967254, 27942.301442463897, 27977.720222797714, 28544.571831422545, 31683.366332673217, 3073.339232065094, 3133.4046531159006, 3179.0462861141796, 3290.967090329114, 3308.503724441353, 255939.0, 257868.99999999814, 258212.0, 258214.9999999981, 259235.0, 28157.71842281592, 47565.52434475611, 47731.5226847734, 47827.521724783, 47894.52105478901, 29986.25034374158, 31624.0, 31730.841345793586, 31812.0, 31927.840360798047, 11398.544058237578, 11726.120540959291, 11726.70683232909, 11728.0, 11730.70673233176, 9196.678116265899, 9356.95321523388, 9358.0, 9362.06379362072, 9364.812703746024, 7716.344110750649, 7746.0704715433385, 7812.375009999187, 7828.334591559667, 7846.764597062097, 3011.909642710722, 3027.2431892026993, 3040.802347847383, 3091.5980922480385, 3134.73354764843, 827.1397746343855, 845.361751877341, 874.7332063720627, 924.9871390827034, 960.0735290444615, 26883.596746048825, 27570.86214568914, 27925.581116283272, 27952.161435156977, 28069.438611227662, 893.7765558610347, 900.4826866729582, 943.0, 955.5843208204453, 1004.6533945788691, 13402.0, 14090.0, 14205.644858878612, 14437.35031923566, 14479.493217737328, 369952.0, 372167.0, 372605.0, 373546.0, 373738.0, 46140.7692961533, 46582.0, 46681.29978050332, 46689.066218675434, 46771.0, 1018.4653057145058, 1075.4891426572628, 1076.5478499030582, 1113.5156207049959, 1134.659602119361, 26478.602820957316, 28263.858680707286, 30230.0, 31141.0, 31814.840925795223, 44883.55116448794, 45774.48240346813, 45812.0, 45812.31281530846, 45815.77092114518, 286068.0, 287751.0, 288266.0, 288790.0, 289306.0, 22383.552328953003, 23312.76687233106, 23323.766762332158, 24248.636270455605, 24249.75750242475, 35718.74921888711, 35818.28363432769, 38018.429723553614, 38140.0, 39694.404583930685, 32532.674673252965, 32816.34367312593, 33163.17092072718, 33167.33665326728, 33464.49803253002, 32589.348213035133, 33004.66995330112, 33218.33563328768, 33868.49197262093, 34245.65754342425, 29130.854345728134, 29526.557101643062, 32846.53650976138, 33033.0, 33177.17057073592, 26751.86624066867, 32938.341233174724, 33203.66796332102, 33249.833750831094, 33335.33329333351, 24748.628770568095, 24768.752312476645, 24841.378965526008, 25575.0, 25639.230823075337], "value": 15644.447888913242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.11949936423235, 341.06888195227015, 342.1224559006149, 345.71305816172577, 365.92681463707265, 11867.940660296697, 12943.482260709561, 15399.769003464957, 15743.291551880164, 16830.821842471032, 16391.83608163921, 16466.588335291624, 16508.25712842922, 16707.248173832177, 16742.58143546412, 4371.781410929457, 4805.327254184417, 5028.522290382408, 5033.194688849785, 5035.496450354954, 5697.202391665171, 5878.941210587904, 5952.255968003999, 5958.255218097738, 5980.581359304851, 4942.308076869242, 5179.611529135311, 5925.34821169671, 5931.347551769323, 5938.88122237555, 5211.062008838408, 5240.082985477564, 5240.816571419982, 5246.711430871301, 5247.895042099137, 4965.280034394992, 5102.464241254652, 5104.157813960692, 5110.7955681773055, 5169.457206993249, 11543.191976561644, 11725.355105469196, 11743.765124697544, 11760.529578816837, 13758.830499407562, 1447.0521808215635, 1580.2098950524737, 1658.1792012953665, 1658.946568928727, 1775.8224177582267, 8513.659453621847, 8744.344174186897, 8908.599113039929, 9119.270458363315, 9141.680041198593, 1717.9484615461556, 1734.332282071399, 1735.158448152645, 1740.4343588333716, 1744.799348074975, 65726.67136664285, 69787.65106174437, 69956.0, 70258.64870675665, 71916.64041679811, 9810.65662701802, 9888.456134912505, 9892.901070989343, 10096.293259471766, 10135.847962280572, 13663.316834158253, 13668.384922678504, 13670.0, 13670.453181872614, 13688.65778355549, 6403.135576697086, 6726.6636668167375, 6757.966210168917, 6893.276205998398, 6896.965515172392, 5986.431289027535, 6039.365866584033, 6194.659293738888, 6204.255489341218, 6213.875722485525, 4058.350663893822, 4140.565240649748, 4246.320588705792, 4272.914541709149, 4389.144116897238, 1722.8621710263149, 1749.212854215608, 1768.294509400619, 1791.6506281275288, 1803.1074618064142, 800.8238187598719, 804.8932717513419, 858.8153546987428, 871.8299931513294, 933.6452148183647, 15774.921125394529, 16265.674686506203, 16899.408520701716, 17116.0, 17167.656646867243, 746.4438992950287, 774.8411575626983, 815.0, 823.6911158315631, 868.7567481105301, 8682.0, 8815.603297851612, 9329.860052099224, 9391.34260601765, 9573.18627916635, 179801.0, 181397.0, 184350.0, 370260.0, 374473.0, 49164.75417622889, 69725.0, 72855.0, 85953.57023214844, 90130.0, 903.40826758472, 911.266430523435, 957.167264479907, 958.5351104714208, 961.0821665309612, 9355.391899526508, 9523.952380238054, 9710.22318214527, 14850.925745371636, 15099.54701358961, 37366.626333737404, 37618.0, 37908.620913791616, 38231.426528601536, 39314.41028384634, 48551.75724121357, 50997.0, 51733.7413312931, 52654.0, 53184.4681553195, 6269.529785266032, 6431.742730290736, 7475.36459400957, 8378.329733621174, 8408.83182336362, 11539.47597601327, 11543.883151549202, 11960.0, 12137.271763694207, 12137.878621213675, 9756.51217439111, 9963.900360996297, 10070.295079344376, 10508.21188410857, 10757.784844303227, 4960.70235785854, 5834.591578589458, 6184.474319682878, 10220.182385409298, 10532.947335263274, 10123.341982771391, 10214.387136771817, 10425.635102771366, 10533.894661053599, 10905.291156074829, 10601.310914790514, 10634.202434817575, 10655.14758819277, 10808.297460665031, 10876.619318324138, 9861.309708320348, 10130.696079117639, 10204.897951020395, 10212.183025358103, 10519.842202367123], "value": 7750.189325029511, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.83842681238, 1297.3513243378313, 1330.7937269723193, 1356.5930220933717, 1622.310518029837, 6179.505639548836, 7381.372583330417, 7604.7338343157935, 7686.346660533847, 7920.405969552292, 7782.377409807216, 7803.258690424401, 7827.373810095193, 7847.058352997648, 7850.725224617139, 2131.26471367378, 2157.072458842697, 2159.4601349662585, 2160.0603737374213, 2160.945976350588, 2116.3756691775884, 2156.8598041127275, 2163.1131236193164, 2163.4375062483787, 2169.761326254111, 2132.1471411435473, 2136.081484961466, 2158.136745301876, 2158.9352319430363, 2175.673648952654, 2129.244118337991, 2134.978650213501, 2137.0, 2138.754043285011, 2138.8075073243326, 2039.8980050997538, 2089.341857314941, 2090.4982804127053, 2090.8536402451837, 2091.5398612305266, 7609.429292803006, 7761.3402860756905, 7762.107357653886, 7764.107127680332, 7768.067831860157, 4071.4503542021744, 4215.725977811432, 4232.4286221360035, 4253.532111467736, 4253.638440732541, 2700.0, 2801.985990070057, 2848.7293707098, 2850.942981140386, 2861.2131663792416, 951.1819834941945, 951.2770294576172, 960.6301573894032, 971.6550624528261, 990.9653162139291, 10226.693199204035, 10280.0, 10287.537060832281, 10322.690319290434, 10324.896751032544, 3747.868824591126, 3870.4774855394726, 3890.124721937598, 3913.2369188008065, 4119.485064366954, 8110.3511719062335, 8238.052623948202, 8257.05043919945, 8259.587020648944, 8261.215184557457, 4273.209456250542, 4277.0, 4279.40088387626, 4279.529251782302, 4281.400603915455, 3373.4265174920324, 3495.580530336325, 3790.753601015925, 4008.478897743333, 4012.598740126022, 3267.6568960258833, 3305.86776528941, 3382.5433566468705, 3400.1329660936303, 3434.8797792077658, 988.051470588229, 995.4723996281982, 997.0328781082338, 1004.8040632076675, 1009.8131845608586, 671.6977360187935, 681.9838440723287, 695.266493848984, 725.8536972862431, 752.6575408189261, 4974.552290293891, 5085.567726743194, 5145.536901678792, 5198.454162312903, 5258.579313654898, 385.3564547206116, 395.6261333040307, 396.9702272329529, 397.5368695469803, 399.8480577380635, 3446.5347178131133, 3461.1001139703353, 3585.229175727231, 3602.621724718867, 3643.0327623083767, 9191.816163676824, 9585.137337639506, 9648.951755241178, 9649.758756031015, 9657.420554766737, 9288.303377246733, 9513.381630194153, 9547.236221102296, 9552.95223523878, 9580.904190958001, 7129.536580122275, 7346.08173978242, 7352.375048120863, 7359.233783891827, 7366.889496657554, 4990.575801056951, 5046.968699088374, 5070.797168113234, 5104.259882316969, 5171.560417364566, 8799.736007919517, 8855.114488551351, 8857.557122143739, 8862.556872156238, 8908.10918908104, 8695.21743043104, 8973.102689730975, 8981.0, 8989.235914947041, 9073.818523629623, 6111.633302001894, 6124.510039196943, 6176.135341052166, 6177.536684748752, 6180.0, 7082.575045497287, 7140.357367836919, 7185.101862267216, 7201.819954501179, 7226.349628533457, 6340.334264902119, 6350.079238510298, 6354.332795056637, 6412.583182093149, 6489.026646002949, 6271.247450305992, 6304.338044505261, 6367.108604795425, 6392.616443013248, 6404.231492220963, 6142.78500252489, 6175.62946223228, 6181.443670069715, 6186.072089186658, 6186.381361863778, 4470.530594287553, 4494.850684754551, 4564.8402305919135, 4571.725696458223, 4700.55344742249, 4469.150861336336, 4500.707454015478, 4545.8181672734045, 4554.658400619901, 4655.464621568563], "value": 3887.521746934057, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.4803827130301, 290.5409453064159, 293.8986049812815, 295.9082684367846, 296.2829951517328, 4826.855194344166, 4827.541383568555, 4832.9275060874115, 4837.637177211705, 4845.4427740809815, 4754.215554433523, 4771.928421073678, 4791.059620853551, 4794.83218087367, 4833.3958255218095, 3532.41715117006, 3644.653757892996, 3683.852645894174, 3738.8317525711273, 3759.7556158849725, 3760.830762615675, 3762.9811850940705, 3767.4348847672795, 3767.4537192107127, 3769.32152212603, 3723.5717892442444, 3731.4589384539227, 3740.4763333133383, 3744.3634582121103, 3761.2665530221616, 3558.1104723819176, 3632.964605087537, 3648.689861361761, 3736.178040831013, 3745.9063523411996, 3280.5729507664005, 3284.3431313737346, 3289.7697161198726, 3290.5393244945726, 3291.3417316536547, 4662.3705799716945, 4663.160631086403, 4679.81280748773, 4689.601383882374, 4693.9530604693855, 4145.834166633331, 4260.829566817323, 4271.295236286009, 4275.679324050709, 4276.508201556799, 4934.333864928225, 4969.279454479117, 4975.502449755032, 4976.751162441863, 4983.327250821165, 3581.7313701472494, 3589.210373717804, 3592.4072528032843, 3593.389123848926, 3595.568531776177, 5091.0, 5199.558037566811, 5204.037253108149, 5224.451432599561, 5259.158534634478, 3626.492291079251, 3634.7273954452994, 3636.70896831621, 3639.4722765199426, 3640.0535860676414, 3684.6868016218923, 3687.760295580779, 3691.778493290411, 3694.685951694082, 3695.963040369562, 4231.555686652919, 4233.534311225762, 4239.766812825324, 4240.978795106004, 4246.065865509583, 4936.71826625387, 4978.77595508203, 4995.25071239317, 5078.7460626968505, 5083.237514372874, 1269.4541347220784, 1287.8068289756611, 1288.7937929931165, 1294.346355090692, 1296.9481220751065, 805.9556724380216, 829.2992421403904, 830.9002919649681, 836.9246767790928, 837.0959363886988, 4110.77390743506, 4310.547392523803, 4380.69335146543, 4442.244818380883, 4516.9159401744, 5135.0, 5140.151874940668, 5169.896602067937, 5188.818391356284, 5191.454897235812, 4514.819407223675, 4520.367148599199, 4524.819007239739, 4526.23054080807, 4529.773511324421, 2605.918543804338, 2714.891404343804, 2717.442924200534, 2735.0, 2751.3259251483514, 4888.2178851383605, 4889.535494128052, 4890.413150421901, 4894.951050489521, 4895.559399654048, 5279.287296215039, 5288.9735551322, 5295.549878260391, 5297.17893726473, 5303.04545181871, 4715.599174070174, 4873.756312184448, 4875.293082502946, 4883.658143929891, 4885.877853053702, 5147.893202961382, 5151.150060240168, 5154.561862241751, 5161.174212126042, 5164.690118592896, 4858.17411040117, 5094.617903657315, 5095.108356037753, 5096.821611243737, 5104.9489505104475, 4857.975710121426, 4861.149298872612, 4865.6107511398295, 4880.999395124062, 4882.7314497701545, 3695.3348397288746, 3719.1260053887854, 3725.1245957200576, 3747.7376583638884, 3760.229153023569, 5175.560077393464, 5191.922121168261, 5198.194279886624, 5201.167813150029, 5224.268602395591, 4814.975925120352, 4974.37820272466, 4985.501449855131, 4989.725565093955, 5001.449840517613, 4953.851384458472, 4955.4301255356095, 4977.328060711902, 4982.128127577587, 5008.298838162587, 4902.460729319843, 4908.662109765394, 4951.801927922843, 4960.330355401975, 4961.900761984813, 4794.184988551885, 4822.300766388784, 4931.334269873521, 5042.193249080129, 5062.0, 4831.849826827169, 4937.7531123444405, 4943.901121977468, 4947.208446648519, 4957.801687932587], "value": 3776.3428198255556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.7846218436995, 596.4184919703287, 632.5192853431392, 633.518525920301, 659.0871642774756, 11984.76030479391, 12597.0, 13041.152325098861, 13166.473341066358, 13258.535951241709, 11810.0, 12099.213551119194, 12108.212966157194, 12113.515459381613, 12122.45448954797, 588.8645611509355, 719.5538765965109, 738.1806195123409, 740.7703611880307, 741.1106671993603, 598.0849300570113, 724.8586525627504, 743.8028922335585, 748.7641392961227, 752.7741677496756, 790.7904405332591, 791.8257983243677, 794.2018271636997, 796.4424902568196, 796.4862663581986, 612.6936531734088, 789.8736202207676, 790.3479629305817, 790.6797746912509, 790.8576456237846, 460.59467668451884, 551.3576683164109, 566.4250785452768, 568.6247076929207, 571.4685342631351, 12494.625161245178, 12518.687032824162, 12537.247765134121, 12541.059420543495, 12546.623601291976, 12237.0, 12443.813342799865, 13173.934130329382, 13410.46358145673, 13725.45098196071, 11531.0, 11764.235324703866, 11792.23350482216, 11936.880631193664, 12481.937590312082, 3943.4676318696897, 4000.4199391088127, 4082.966513336732, 4084.1657895259564, 4131.277026520347, 66457.3354266456, 66670.66664666694, 66790.3320966789, 66869.0, 67698.0, 10119.24105692076, 10267.178625709925, 10397.896021039693, 10557.524911379007, 10686.0, 18472.353467628567, 18520.81479185191, 18598.907005464887, 18654.53363665892, 18897.244110235435, 6355.841103972438, 6407.7757278495965, 6407.839804004937, 6418.77534286307, 6597.175353080865, 3579.3557159711504, 3606.74752767309, 3620.4569314602495, 3632.3643362412, 3637.1270894985014, 2509.6737424134744, 2593.2090712332542, 2598.2854714953537, 3063.8161710297454, 3223.7098661120613, 501.3299581730638, 502.18395107949584, 508.7278306106289, 529.9099153143975, 540.5648452995298, 415.81911868336766, 487.24720307125574, 496.2184559319087, 497.3186734174132, 513.7200225876906, 7552.207018263113, 8116.228958248957, 8146.103928567972, 8248.381371397048, 8336.8332633347, 486.96347773917097, 489.06344350568975, 490.7742438478269, 490.8502906613517, 504.19832466378637, 3647.562292524861, 3674.5039419678537, 3849.557300910431, 3852.653261206449, 3897.8830635080994, 58035.0, 58045.41954580485, 58171.0, 59143.40856591465, 89881.0, 86930.0, 87883.56058219796, 88786.01079389433, 89328.0, 92659.53670231605, 80975.59512202519, 82387.0, 82722.58638706768, 83231.0, 83669.58165209135, 7203.891941620985, 7427.145878224073, 7464.328210461084, 7634.618269086634, 7638.274363935418, 46851.53148468472, 47158.29262560996, 48199.75900120477, 49104.0, 49121.75439122781, 60187.0, 62014.689926550076, 67090.66454667696, 68062.3193768056, 70040.0, 9210.861837072584, 9244.95377523108, 9332.327385918828, 9498.382605130644, 9606.0, 9420.764480888032, 9802.803943921224, 9837.697899034267, 10088.747781305527, 10252.538635761259, 9763.658271960447, 10080.395176289447, 10268.845967310346, 11477.770444591228, 11806.704832379259, 11708.70728231767, 11929.701757456132, 12389.43354032302, 12573.685657858627, 13033.674158146123, 11129.499172537093, 11366.261193022427, 11562.248453850472, 12152.696182595506, 12402.441890115148, 9362.719118426457, 9429.575669095044, 9605.423674579548, 10192.337498062601, 10267.7433064174, 8883.554612138025, 9234.261259099121, 10030.949845250727, 10098.899011009797, 10146.391216527032], "value": 6299.51548734334, "unit": "score"}], "name": "dromaeo_css", "value": 6111.1524257749825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 115.0, 114.0, 115.0, 114.0, 115.0, 115.0, 113.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 123.0, 164.0, 128.0, 128.0, 126.0, 126.0, 160.0, 126.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 162.0, 161.0, 162.0, 161.0, 162.0, 161.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 77.0, 121.0, 78.0, 78.0, 78.0, 76.0, 101.0, 75.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 135.0, 86.0, 85.0, 83.0, 120.0, 83.0, 84.0, 112.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 64.0, 67.0, 65.0, 65.0, 65.0, 66.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 84.0, 82.0, 88.0, 81.0, 82.0, 85.0, 81.0, 87.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 142.0, 142.0, 144.0, 148.0, 132.0, 135.0, 141.0, 171.0, 144.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 174.0, 175.0, 172.0, 176.0, 171.0, 177.0, 171.0, 172.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 72.0, 69.0, 69.0, 68.0, 69.0, 69.0, 68.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1548.7, "alertThreshold": 2.0}]} 11:01:46 INFO - Return code: 0 11:01:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:01:46 INFO - # TBPL SUCCESS # 11:01:46 INFO - Running post-action listener: _resource_record_post_action 11:01:46 INFO - [mozharness: 2016-09-17 18:01:46.185612Z] Finished run-tests step (success) 11:01:46 INFO - Running post-run listener: _resource_record_post_run 11:01:46 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 396517376; Read time: 0; Write time: 298292 11:01:46 INFO - TinderboxPrint: CPU usage
13.1% 11:01:46 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:01:46 INFO - TinderboxPrint: I/O write bytes / time
396,517,376 / 298,292 11:01:46 INFO - TinderboxPrint: CPU idle
8,336.8 (86.9%) 11:01:46 INFO - TinderboxPrint: CPU user
1,202.1 (12.5%) 11:01:46 INFO - TinderboxPrint: Swap in / out
0 / 0 11:01:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106029056; Read time: 0; Write time: 91692 11:01:46 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 290488320; Read time: 0; Write time: 206600 11:01:46 INFO - Running post-run listener: _upload_blobber_files 11:01:46 INFO - Blob upload gear active. 11:01:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:01:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:01:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:01:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:01:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:01:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:47 INFO - (blobuploader) - INFO - Done attempting. 11:01:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:01:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:01:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:48 INFO - (blobuploader) - INFO - Done attempting. 11:01:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:01:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:01:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:52 INFO - (blobuploader) - INFO - Done attempting. 11:01:52 INFO - (blobuploader) - INFO - Iteration through files over. 11:01:52 INFO - Return code: 0 11:01:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:01:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:01:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/562000071c3b11bbc10b88d94184adb05522ab2b80fbe70bcec138c5d9199af313fc7bdbc7840a6aa57f3b8e547e582063246dcc2a8c9c6cd9c7a462a533f095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/663dc8b9bd9401de51c6381da729a67a3d32375f93a54573837c07f42cd77cc55e9e5e476a23b02ad697848b1c2f965779ce7ae91cdaefb758ec37839eee6282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/34baf5e9bf8b4b599132de59140a5fc008696d73f7adf27854f233043f9bc1b8051f70789a1760d00da154c177191f1e744cf0e0a65dfef01d3639e1ba495fd3"} 11:01:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:01:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:01:52 INFO - Contents: 11:01:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/562000071c3b11bbc10b88d94184adb05522ab2b80fbe70bcec138c5d9199af313fc7bdbc7840a6aa57f3b8e547e582063246dcc2a8c9c6cd9c7a462a533f095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/663dc8b9bd9401de51c6381da729a67a3d32375f93a54573837c07f42cd77cc55e9e5e476a23b02ad697848b1c2f965779ce7ae91cdaefb758ec37839eee6282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/34baf5e9bf8b4b599132de59140a5fc008696d73f7adf27854f233043f9bc1b8051f70789a1760d00da154c177191f1e744cf0e0a65dfef01d3639e1ba495fd3"} 11:01:52 INFO - Running post-run listener: copy_logs_to_upload_dir 11:01:52 INFO - Copying logs to upload dir... 11:01:52 INFO - mkdir: /builds/slave/test/build/upload/logs 11:01:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.207332 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 11:01:51.945560) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 11:01:51.953404) ========= 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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 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/562000071c3b11bbc10b88d94184adb05522ab2b80fbe70bcec138c5d9199af313fc7bdbc7840a6aa57f3b8e547e582063246dcc2a8c9c6cd9c7a462a533f095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/663dc8b9bd9401de51c6381da729a67a3d32375f93a54573837c07f42cd77cc55e9e5e476a23b02ad697848b1c2f965779ce7ae91cdaefb758ec37839eee6282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/34baf5e9bf8b4b599132de59140a5fc008696d73f7adf27854f233043f9bc1b8051f70789a1760d00da154c177191f1e744cf0e0a65dfef01d3639e1ba495fd3"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64/1474061778/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/562000071c3b11bbc10b88d94184adb05522ab2b80fbe70bcec138c5d9199af313fc7bdbc7840a6aa57f3b8e547e582063246dcc2a8c9c6cd9c7a462a533f095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/663dc8b9bd9401de51c6381da729a67a3d32375f93a54573837c07f42cd77cc55e9e5e476a23b02ad697848b1c2f965779ce7ae91cdaefb758ec37839eee6282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/34baf5e9bf8b4b599132de59140a5fc008696d73f7adf27854f233043f9bc1b8051f70789a1760d00da154c177191f1e744cf0e0a65dfef01d3639e1ba495fd3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 11:01:51.981860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 11:01:51.982253) ========= 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=bb9b54edd4a927dc735d112f000001d9-1474134069.28625-894031238 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 11:01:52.067971) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 11:01:52.068342) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 11:01:52.068722) ========= ========= Total master_lag: 0.20 =========