builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-039 starttime: 1474280190.14 results: success (0) buildid: 20160919023017 builduid: 208aaab65efc4e7d9adbb79be558c597 revision: 651b30ec6c5c4820559027fbda9bdc15fa504930 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.143458) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.143961) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.144310) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.159851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.160502) ========= 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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 03:16:30-- 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% 40.5M=0s 2016-09-19 03:16:31 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.359626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.360098) ========= 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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016031 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:30.412910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 03:16:30.413309) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 651b30ec6c5c4820559027fbda9bdc15fa504930 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 651b30ec6c5c4820559027fbda9bdc15fa504930 --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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 03:16:31,280 truncating revision to first 12 chars 2016-09-19 03:16:31,280 Setting DEBUG logging. 2016-09-19 03:16:31,280 attempt 1/10 2016-09-19 03:16:31,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/651b30ec6c5c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 03:16:32,077 unpacking tar archive at: autoland-651b30ec6c5c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.129073 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 03:16:31.559299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:31.560195) ========= script_repo_revision: 651b30ec6c5c4820559027fbda9bdc15fa504930 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:31.561086) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:31.561713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 03:16:31.573443) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-19 03:16:31.573812) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:32 INFO - MultiFileLogger online at 20160919 03:16:32 in /builds/slave/test 03:16:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 03:16:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:32 INFO - {'append_to_log': False, 03:16:32 INFO - 'base_work_dir': '/builds/slave/test', 03:16:32 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 03:16:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:32 INFO - 'branch': 'Autoland-Non-PGO', 03:16:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:32 INFO - 'config_files': ('talos/linux_config.py',), 03:16:32 INFO - 'default_actions': ('clobber', 03:16:32 INFO - 'read-buildbot-config', 03:16:32 INFO - 'download-and-extract', 03:16:32 INFO - 'populate-webroot', 03:16:32 INFO - 'create-virtualenv', 03:16:32 INFO - 'install', 03:16:32 INFO - 'run-tests'), 03:16:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:32 INFO - 'download_minidump_stackwalk': True, 03:16:32 INFO - 'download_symbols': 'ondemand', 03:16:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:16:32 INFO - 'tooltool.py': '/tools/tooltool.py', 03:16:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:16:32 INFO - '/tools/misc-python/virtualenv.py')}, 03:16:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:32 INFO - 'installer_path': 'installer.exe', 03:16:32 INFO - 'log_level': 'info', 03:16:32 INFO - 'log_name': 'talos', 03:16:32 INFO - 'log_to_console': True, 03:16:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:32 INFO - 'opt_config_files': (), 03:16:32 INFO - 'pip_index': False, 03:16:32 INFO - 'sps_profile': False, 03:16:32 INFO - 'sps_profile_interval': 0, 03:16:32 INFO - 'suite': 'dromaeojs', 03:16:32 INFO - 'system_bits': '32', 03:16:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:32 INFO - 'title': 'talos-linux64-ix-039', 03:16:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:32 INFO - 'use_talos_json': True, 03:16:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:32 INFO - 'work_dir': 'build'} 03:16:32 INFO - [mozharness: 2016-09-19 10:16:32.532718Z] Running clobber step. 03:16:32 INFO - Running pre-action listener: _resource_record_pre_action 03:16:32 INFO - Running main action method: clobber 03:16:32 INFO - rmtree: /builds/slave/test/build 03:16:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:32 INFO - Running post-action listener: _resource_record_post_action 03:16:32 INFO - [mozharness: 2016-09-19 10:16:32.987950Z] Finished clobber step (success) 03:16:32 INFO - [mozharness: 2016-09-19 10:16:32.988050Z] Running read-buildbot-config step. 03:16:32 INFO - Running pre-action listener: _resource_record_pre_action 03:16:32 INFO - Running main action method: read_buildbot_config 03:16:32 INFO - Using buildbot properties: 03:16:32 INFO - { 03:16:32 INFO - "project": "", 03:16:32 INFO - "product": "firefox", 03:16:32 INFO - "script_repo_revision": "production", 03:16:32 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 03:16:32 INFO - "repository": "", 03:16:32 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 03:16:32 INFO - "buildid": "20160919023017", 03:16:32 INFO - "pgo_build": "False", 03:16:32 INFO - "basedir": "/builds/slave/test", 03:16:32 INFO - "buildnumber": 471, 03:16:32 INFO - "slavename": "talos-linux64-ix-039", 03:16:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:16:32 INFO - "platform": "ubuntu64_hw", 03:16:32 INFO - "scheduler": "tests-autoland-linux64-talos", 03:16:32 INFO - "branch": "autoland", 03:16:32 INFO - "repo_path": "integration/autoland", 03:16:32 INFO - "revision": "651b30ec6c5c4820559027fbda9bdc15fa504930", 03:16:32 INFO - "stage_platform": "linux64", 03:16:32 INFO - "builduid": "208aaab65efc4e7d9adbb79be558c597", 03:16:32 INFO - "slavebuilddir": "test" 03:16:32 INFO - } 03:16:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 03:16:32 INFO - Running post-action listener: _resource_record_post_action 03:16:32 INFO - [mozharness: 2016-09-19 10:16:32.990507Z] Finished read-buildbot-config step (success) 03:16:32 INFO - [mozharness: 2016-09-19 10:16:32.990597Z] Running download-and-extract step. 03:16:32 INFO - Running pre-action listener: _resource_record_pre_action 03:16:32 INFO - Running main action method: download_and_extract 03:16:32 INFO - mkdir: /builds/slave/test/build/tests 03:16: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')]} 03:16:32 INFO - trying https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:16:32 INFO - Downloading https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:16:33 INFO - Downloaded 1361 bytes. 03:16:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:16:33 INFO - Using the following test package requirements: 03:16:33 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 03:16:33 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:16:33 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:16:33 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'jsshell-linux-x86_64.zip'], 03:16:33 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:16:33 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 03:16:33 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:16:33 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:16:33 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:16:33 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:16:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:16:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:16:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:16:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:33 INFO - Fetch https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 03:16:34 INFO - Expected file size: 20365662 03:16:39 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 03:16:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:16:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:39 INFO - Fetch https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 03:16:39 INFO - Expected file size: 20365662 03:16:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 03:16:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:16:44 INFO - Fetch https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 03:16:45 INFO - Expected file size: 14414048 03:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:46 INFO - trying https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:16:46 INFO - Downloading https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:16:48 INFO - Downloaded 55595237 bytes. 03:16:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:16:48 INFO - mkdir: /builds/slave/test/properties 03:16:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:48 INFO - Contents: 03:16:48 INFO - build_url:https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:16:49 INFO - Running post-action listener: _resource_record_post_action 03:16:49 INFO - Running post-action listener: set_extra_try_arguments 03:16:49 INFO - [mozharness: 2016-09-19 10:16:49.084012Z] Finished download-and-extract step (success) 03:16:49 INFO - [mozharness: 2016-09-19 10:16:49.084240Z] Running populate-webroot step. 03:16:49 INFO - Running pre-action listener: _resource_record_pre_action 03:16:49 INFO - Running main action method: populate_webroot 03:16:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:16:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:16:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:16:49 INFO - '--tppagecycles', 03:16:49 INFO - '7'], 03:16:49 INFO - 'tests': ['tsvgm']}}, 03:16:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:16:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:16:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['damp', 'tps']}, 03:16:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'tests': ['damp', 'tps']}, 03:16:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['dromaeo_dom']}, 03:16:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['basic_compositor_video']}, 03:16:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:16:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['a11yr', 03:16:49 INFO - 'ts_paint', 03:16:49 INFO - 'tpaint', 03:16:49 INFO - 'sessionrestore', 03:16:49 INFO - 'sessionrestore_no_auto_restore', 03:16:49 INFO - 'tabpaint']}, 03:16:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['tsvgx', 03:16:49 INFO - 'tsvgr_opacity', 03:16:49 INFO - 'tart', 03:16:49 INFO - 'tscrollx', 03:16:49 INFO - 'cart']}, 03:16:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:49 INFO - 'tsvgr_opacity', 03:16:49 INFO - 'tart', 03:16:49 INFO - 'tscrollx', 03:16:49 INFO - 'cart']}, 03:16:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'talos_options': ['--disable-e10s'], 03:16:49 INFO - 'tests': ['tp5o']}, 03:16:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'tests': ['tp5o']}, 03:16:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'talos_options': ['--disable-e10s', 03:16:49 INFO - '--xperf_path', 03:16:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:49 INFO - 'tests': ['tp5n']}, 03:16:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:49 INFO - 'talos_options': ['--xperf_path', 03:16:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:49 INFO - 'tests': ['tp5n']}}, 03:16:49 INFO - 'talos.zip': {'path': '', 03:16:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:16:49 INFO - Running post-action listener: _resource_record_post_action 03:16:49 INFO - [mozharness: 2016-09-19 10:16:49.101633Z] Finished populate-webroot step (success) 03:16:49 INFO - [mozharness: 2016-09-19 10:16:49.101722Z] Running create-virtualenv step. 03:16:49 INFO - Running pre-action listener: _resource_record_pre_action 03:16:49 INFO - Running main action method: create_virtualenv 03:16:49 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:49 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 03:16:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:16:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:49 INFO - Using real prefix '/usr' 03:16:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:51 INFO - Installing pip.................done. 03:16:51 INFO - Return code: 0 03:16:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16:51 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')]} 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 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')]} 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:51 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 03:16:51 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 03:16:51 INFO - Using env: {'DISPLAY': ':0', 03:16:51 INFO - 'HOME': '/home/cltbld', 03:16:51 INFO - 'LANG': 'en_US.UTF-8', 03:16:51 INFO - 'LANGUAGE': 'en_US:en', 03:16:51 INFO - 'LOGNAME': 'cltbld', 03:16:51 INFO - 'MAIL': '/var/mail/cltbld', 03:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:51 INFO - 'MOZ_NO_REMOTE': '1', 03:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:51 INFO - 'NO_EM_RESTART': '1', 03:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:51 INFO - 'PWD': '/builds/slave/test', 03:16:51 INFO - 'SHELL': '/bin/bash', 03:16:51 INFO - 'SHLVL': '1', 03:16:51 INFO - 'TERM': 'linux', 03:16:51 INFO - 'TMOUT': '86400', 03:16:51 INFO - 'USER': 'cltbld', 03:16:51 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:51 INFO - '_': '/tools/buildbot/bin/python'} 03:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:51 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 03:16:51 INFO - Cleaning up... 03:16:51 INFO - Return code: 0 03:16:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:51 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')]} 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 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')]} 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:51 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 03:16:51 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 03:16:51 INFO - Using env: {'DISPLAY': ':0', 03:16:51 INFO - 'HOME': '/home/cltbld', 03:16:51 INFO - 'LANG': 'en_US.UTF-8', 03:16:51 INFO - 'LANGUAGE': 'en_US:en', 03:16:51 INFO - 'LOGNAME': 'cltbld', 03:16:51 INFO - 'MAIL': '/var/mail/cltbld', 03:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:51 INFO - 'MOZ_NO_REMOTE': '1', 03:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:51 INFO - 'NO_EM_RESTART': '1', 03:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:51 INFO - 'PWD': '/builds/slave/test', 03:16:51 INFO - 'SHELL': '/bin/bash', 03:16:51 INFO - 'SHLVL': '1', 03:16:51 INFO - 'TERM': 'linux', 03:16:51 INFO - 'TMOUT': '86400', 03:16:51 INFO - 'USER': 'cltbld', 03:16:51 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:51 INFO - '_': '/tools/buildbot/bin/python'} 03:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:52 INFO - Downloading/unpacking psutil>=3.1.1 03:16:52 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 03:16:52 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 03:16:52 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 03:16:52 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 03:16:52 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 03:16:52 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 03:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:54 INFO - Installing collected packages: psutil 03:16:54 INFO - Running setup.py install for psutil 03:16:54 INFO - building 'psutil._psutil_linux' extension 03:16:54 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 03:16:54 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 03:16:54 INFO - building 'psutil._psutil_posix' extension 03:16:54 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 03:16:54 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 03:16:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:54 INFO - Successfully installed psutil 03:16:54 INFO - Cleaning up... 03:16:54 INFO - Return code: 0 03:16:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16:54 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')]} 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:54 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')]} 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:54 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:54 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 03:16:54 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 03:16:54 INFO - Using env: {'DISPLAY': ':0', 03:16:54 INFO - 'HOME': '/home/cltbld', 03:16:54 INFO - 'LANG': 'en_US.UTF-8', 03:16:54 INFO - 'LANGUAGE': 'en_US:en', 03:16:54 INFO - 'LOGNAME': 'cltbld', 03:16:54 INFO - 'MAIL': '/var/mail/cltbld', 03:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:54 INFO - 'MOZ_NO_REMOTE': '1', 03:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:54 INFO - 'NO_EM_RESTART': '1', 03:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:54 INFO - 'PWD': '/builds/slave/test', 03:16:54 INFO - 'SHELL': '/bin/bash', 03:16:54 INFO - 'SHLVL': '1', 03:16:54 INFO - 'TERM': 'linux', 03:16:54 INFO - 'TMOUT': '86400', 03:16:54 INFO - 'USER': 'cltbld', 03:16:54 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:54 INFO - '_': '/tools/buildbot/bin/python'} 03:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:54 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 03:16:54 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 03:16:54 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 03:16:54 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 03:16:54 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 03:16:54 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 03:16:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:56 INFO - Installing collected packages: mozsystemmonitor 03:16:56 INFO - Running setup.py install for mozsystemmonitor 03:16:56 INFO - Successfully installed mozsystemmonitor 03:16:56 INFO - Cleaning up... 03:16:56 INFO - Return code: 0 03:16:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16:56 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')]} 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:56 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')]} 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:56 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:56 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 03:16:56 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 03:16:56 INFO - Using env: {'DISPLAY': ':0', 03:16:56 INFO - 'HOME': '/home/cltbld', 03:16:56 INFO - 'LANG': 'en_US.UTF-8', 03:16:56 INFO - 'LANGUAGE': 'en_US:en', 03:16:56 INFO - 'LOGNAME': 'cltbld', 03:16:56 INFO - 'MAIL': '/var/mail/cltbld', 03:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:56 INFO - 'MOZ_NO_REMOTE': '1', 03:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:56 INFO - 'NO_EM_RESTART': '1', 03:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:56 INFO - 'PWD': '/builds/slave/test', 03:16:56 INFO - 'SHELL': '/bin/bash', 03:16:56 INFO - 'SHLVL': '1', 03:16:56 INFO - 'TERM': 'linux', 03:16:56 INFO - 'TMOUT': '86400', 03:16:56 INFO - 'USER': 'cltbld', 03:16:56 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:56 INFO - '_': '/tools/buildbot/bin/python'} 03:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:57 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:57 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 03:16:57 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 03:16:57 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 03:16:57 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 03:16:57 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 03:16:57 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 03:16:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16:59 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 03:16:59 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 03:16:59 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 03:16:59 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 03:16:59 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 03:16:59 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 03:16:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:59 INFO - warning: no files found matching '*.txt' 03:16:59 INFO - no previously-included directories found matching 'build' 03:16:59 INFO - no previously-included directories found matching 'dist' 03:16:59 INFO - no previously-included directories found matching '.git*' 03:16:59 INFO - Installing collected packages: jsonschema, functools32 03:16:59 INFO - Running setup.py install for functools32 03:16:59 INFO - warning: no files found matching '*.txt' 03:16:59 INFO - no previously-included directories found matching 'build' 03:16:59 INFO - no previously-included directories found matching 'dist' 03:16:59 INFO - no previously-included directories found matching '.git*' 03:16:59 INFO - Successfully installed jsonschema functools32 03:16:59 INFO - Cleaning up... 03:16:59 INFO - Return code: 0 03:16:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16: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')]} 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:59 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:59 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 03:16:59 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 03:16:59 INFO - Using env: {'DISPLAY': ':0', 03:16:59 INFO - 'HOME': '/home/cltbld', 03:16:59 INFO - 'LANG': 'en_US.UTF-8', 03:16:59 INFO - 'LANGUAGE': 'en_US:en', 03:16:59 INFO - 'LOGNAME': 'cltbld', 03:16:59 INFO - 'MAIL': '/var/mail/cltbld', 03:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:59 INFO - 'MOZ_NO_REMOTE': '1', 03:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:59 INFO - 'NO_EM_RESTART': '1', 03:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:59 INFO - 'PWD': '/builds/slave/test', 03:16:59 INFO - 'SHELL': '/bin/bash', 03:16:59 INFO - 'SHLVL': '1', 03:16:59 INFO - 'TERM': 'linux', 03:16:59 INFO - 'TMOUT': '86400', 03:16:59 INFO - 'USER': 'cltbld', 03:16:59 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:59 INFO - '_': '/tools/buildbot/bin/python'} 03:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:16:59 INFO - Cleaning up... 03:16:59 INFO - Return code: 0 03:16:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16: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')]} 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:59 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:16:59 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 03:16:59 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 03:16:59 INFO - Using env: {'DISPLAY': ':0', 03:16:59 INFO - 'HOME': '/home/cltbld', 03:16:59 INFO - 'LANG': 'en_US.UTF-8', 03:16:59 INFO - 'LANGUAGE': 'en_US:en', 03:16:59 INFO - 'LOGNAME': 'cltbld', 03:16:59 INFO - 'MAIL': '/var/mail/cltbld', 03:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:59 INFO - 'MOZ_NO_REMOTE': '1', 03:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:59 INFO - 'NO_EM_RESTART': '1', 03:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:59 INFO - 'PWD': '/builds/slave/test', 03:16:59 INFO - 'SHELL': '/bin/bash', 03:16:59 INFO - 'SHLVL': '1', 03:16:59 INFO - 'TERM': 'linux', 03:16:59 INFO - 'TMOUT': '86400', 03:16:59 INFO - 'USER': 'cltbld', 03:16:59 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:59 INFO - '_': '/tools/buildbot/bin/python'} 03:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:17:00 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 03:17:00 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 03:17:00 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 03:17:00 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 03:17:00 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 03:17:00 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 03:17:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:17:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:17:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 INFO - Downloading docopt-0.6.1.tar.gz 03:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:17:02 INFO - Installing collected packages: blobuploader, requests, docopt 03:17:02 INFO - Running setup.py install for blobuploader 03:17:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:17:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:17:02 INFO - Running setup.py install for requests 03:17:03 INFO - Running setup.py install for docopt 03:17:03 INFO - Successfully installed blobuploader requests docopt 03:17:03 INFO - Cleaning up... 03:17:03 INFO - Return code: 0 03:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:03 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:17:03 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 03:17:03 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 03:17:03 INFO - Using env: {'DISPLAY': ':0', 03:17:03 INFO - 'HOME': '/home/cltbld', 03:17:03 INFO - 'LANG': 'en_US.UTF-8', 03:17:03 INFO - 'LANGUAGE': 'en_US:en', 03:17:03 INFO - 'LOGNAME': 'cltbld', 03:17:03 INFO - 'MAIL': '/var/mail/cltbld', 03:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:03 INFO - 'MOZ_NO_REMOTE': '1', 03:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:03 INFO - 'NO_EM_RESTART': '1', 03:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:03 INFO - 'PWD': '/builds/slave/test', 03:17:03 INFO - 'SHELL': '/bin/bash', 03:17:03 INFO - 'SHLVL': '1', 03:17:03 INFO - 'TERM': 'linux', 03:17:03 INFO - 'TMOUT': '86400', 03:17:03 INFO - 'USER': 'cltbld', 03:17:03 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:03 INFO - '_': '/tools/buildbot/bin/python'} 03:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:03 INFO - Running setup.py (path:/tmp/pip-x7i5T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:03 INFO - Running setup.py (path:/tmp/pip-JP9Sw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:03 INFO - Running setup.py (path:/tmp/pip-fyHBHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:03 INFO - Running setup.py (path:/tmp/pip-VJsfdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:03 INFO - Running setup.py (path:/tmp/pip-Lf0h9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:04 INFO - Running setup.py (path:/tmp/pip-aq3N6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:04 INFO - Running setup.py (path:/tmp/pip-qM96Ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:04 INFO - Running setup.py (path:/tmp/pip-lrLtJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:04 INFO - Running setup.py (path:/tmp/pip-SHh_de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:04 INFO - Running setup.py (path:/tmp/pip-KnfGPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:04 INFO - Running setup.py (path:/tmp/pip-T0DqIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:04 INFO - Running setup.py (path:/tmp/pip-tiDU7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:04 INFO - Running setup.py (path:/tmp/pip-plI2aU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:04 INFO - Running setup.py (path:/tmp/pip-Lro7LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:04 INFO - Running setup.py (path:/tmp/pip-LTRCQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:04 INFO - Running setup.py (path:/tmp/pip-O_ROeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:04 INFO - Running setup.py (path:/tmp/pip-XpCgab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:17:04 INFO - Running setup.py install for manifestparser 03:17:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:17:04 INFO - Running setup.py install for mozcrash 03:17:05 INFO - Running setup.py install for mozdebug 03:17:05 INFO - Running setup.py install for mozdevice 03:17:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Running setup.py install for mozfile 03:17:05 INFO - Running setup.py install for mozhttpd 03:17:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Running setup.py install for mozinfo 03:17:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Running setup.py install for mozInstall 03:17:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Running setup.py install for mozleak 03:17:05 INFO - Running setup.py install for mozlog 03:17:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:17:05 INFO - Running setup.py install for moznetwork 03:17:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Running setup.py install for mozprocess 03:17:06 INFO - Running setup.py install for mozprofile 03:17:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Running setup.py install for mozrunner 03:17:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Running setup.py install for mozscreenshot 03:17:06 INFO - Running setup.py install for moztest 03:17:06 INFO - Running setup.py install for mozversion 03:17:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:06 INFO - Cleaning up... 03:17:06 INFO - Return code: 0 03:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:06 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:17:06 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 03:17:06 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 03:17:06 INFO - Using env: {'DISPLAY': ':0', 03:17:06 INFO - 'HOME': '/home/cltbld', 03:17:06 INFO - 'LANG': 'en_US.UTF-8', 03:17:06 INFO - 'LANGUAGE': 'en_US:en', 03:17:06 INFO - 'LOGNAME': 'cltbld', 03:17:06 INFO - 'MAIL': '/var/mail/cltbld', 03:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:06 INFO - 'MOZ_NO_REMOTE': '1', 03:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:06 INFO - 'NO_EM_RESTART': '1', 03:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:06 INFO - 'PWD': '/builds/slave/test', 03:17:06 INFO - 'SHELL': '/bin/bash', 03:17:06 INFO - 'SHLVL': '1', 03:17:06 INFO - 'TERM': 'linux', 03:17:06 INFO - 'TMOUT': '86400', 03:17:06 INFO - 'USER': 'cltbld', 03:17:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:06 INFO - '_': '/tools/buildbot/bin/python'} 03:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:06 INFO - Running setup.py (path:/tmp/pip-L6QnPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:06 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)) 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:06 INFO - Running setup.py (path:/tmp/pip-Avsi7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:07 INFO - Running setup.py (path:/tmp/pip-aBTPnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:07 INFO - Running setup.py (path:/tmp/pip-urfZGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:07 INFO - Running setup.py (path:/tmp/pip-NFm2Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:07 INFO - Running setup.py (path:/tmp/pip-yo8peV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:07 INFO - Running setup.py (path:/tmp/pip-tZ8JFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:07 INFO - Running setup.py (path:/tmp/pip-VoD99p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:07 INFO - Running setup.py (path:/tmp/pip-D0c27A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:07 INFO - Running setup.py (path:/tmp/pip-a1xhcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:07 INFO - Running setup.py (path:/tmp/pip-Ckecu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:07 INFO - Running setup.py (path:/tmp/pip-8hedPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:07 INFO - Running setup.py (path:/tmp/pip-60OFF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:07 INFO - Running setup.py (path:/tmp/pip-qrQ_ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:07 INFO - Running setup.py (path:/tmp/pip-suWsz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:07 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)) 03:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:08 INFO - Running setup.py (path:/tmp/pip-_kE9Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:08 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)) 03:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:08 INFO - Running setup.py (path:/tmp/pip-Q5YEO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:08 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)) 03:17:08 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)) 03:17:08 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)) 03:17:08 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)) 03:17:08 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)) 03:17:08 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)) 03:17:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:10 INFO - Downloading blessings-1.6.tar.gz 03:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:10 INFO - Installing collected packages: blessings 03:17:10 INFO - Running setup.py install for blessings 03:17:10 INFO - Successfully installed blessings 03:17:10 INFO - Cleaning up... 03:17:10 INFO - Return code: 0 03:17:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:10 INFO - Reading from file tmpfile_stdout 03:17:10 INFO - Current package versions: 03:17:10 INFO - argparse == 1.2.1 03:17:10 INFO - blessings == 1.6 03:17:10 INFO - blobuploader == 1.2.4 03:17:10 INFO - docopt == 0.6.1 03:17:10 INFO - functools32 == 3.2.3-2 03:17:10 INFO - jsonschema == 2.5.1 03:17:10 INFO - manifestparser == 1.1 03:17:10 INFO - mozInstall == 1.12 03:17:10 INFO - mozcrash == 0.17 03:17:10 INFO - mozdebug == 0.1 03:17:10 INFO - mozdevice == 0.48 03:17:10 INFO - mozfile == 1.2 03:17:10 INFO - mozhttpd == 0.7 03:17:10 INFO - mozinfo == 0.9 03:17:10 INFO - mozleak == 0.1 03:17:10 INFO - mozlog == 3.4 03:17:10 INFO - moznetwork == 0.27 03:17:10 INFO - mozprocess == 0.23 03:17:10 INFO - mozprofile == 0.28 03:17:10 INFO - mozrunner == 6.12 03:17:10 INFO - mozscreenshot == 0.1 03:17:10 INFO - mozsystemmonitor == 0.3 03:17:10 INFO - moztest == 0.7 03:17:10 INFO - mozversion == 1.4 03:17:10 INFO - psutil == 3.1.1 03:17:10 INFO - requests == 1.2.3 03:17:10 INFO - wsgiref == 0.1.2 03:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:10 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')]} 03:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:10 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')]} 03:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:10 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:17:10 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 03:17:10 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 03:17:10 INFO - Using env: {'DISPLAY': ':0', 03:17:10 INFO - 'HOME': '/home/cltbld', 03:17:10 INFO - 'LANG': 'en_US.UTF-8', 03:17:10 INFO - 'LANGUAGE': 'en_US:en', 03:17:10 INFO - 'LOGNAME': 'cltbld', 03:17:10 INFO - 'MAIL': '/var/mail/cltbld', 03:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:10 INFO - 'MOZ_NO_REMOTE': '1', 03:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:10 INFO - 'NO_EM_RESTART': '1', 03:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:10 INFO - 'PWD': '/builds/slave/test', 03:17:10 INFO - 'SHELL': '/bin/bash', 03:17:10 INFO - 'SHLVL': '1', 03:17:10 INFO - 'TERM': 'linux', 03:17:10 INFO - 'TMOUT': '86400', 03:17:10 INFO - 'USER': 'cltbld', 03:17:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:10 INFO - '_': '/tools/buildbot/bin/python'} 03:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 INFO - Cleaning up... 03:17:10 INFO - Return code: 0 03:17:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:10 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')]} 03:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:10 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')]} 03:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:10 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 0x7f0add45bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, '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': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', '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 03:17:10 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 03:17:10 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 03:17:10 INFO - Using env: {'DISPLAY': ':0', 03:17:10 INFO - 'HOME': '/home/cltbld', 03:17:10 INFO - 'LANG': 'en_US.UTF-8', 03:17:10 INFO - 'LANGUAGE': 'en_US:en', 03:17:10 INFO - 'LOGNAME': 'cltbld', 03:17:10 INFO - 'MAIL': '/var/mail/cltbld', 03:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:10 INFO - 'MOZ_NO_REMOTE': '1', 03:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:10 INFO - 'NO_EM_RESTART': '1', 03:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:10 INFO - 'PWD': '/builds/slave/test', 03:17:10 INFO - 'SHELL': '/bin/bash', 03:17:10 INFO - 'SHLVL': '1', 03:17:10 INFO - 'TERM': 'linux', 03:17:10 INFO - 'TMOUT': '86400', 03:17:10 INFO - 'USER': 'cltbld', 03:17:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:10 INFO - '_': '/tools/buildbot/bin/python'} 03:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:10 INFO - Cleaning up... 03:17:10 INFO - Return code: 0 03:17:10 INFO - Running post-action listener: _resource_record_post_action 03:17:10 INFO - Running post-action listener: _start_resource_monitoring 03:17:10 INFO - Starting resource monitoring. 03:17:10 INFO - [mozharness: 2016-09-19 10:17:10.957199Z] Finished create-virtualenv step (success) 03:17:10 INFO - [mozharness: 2016-09-19 10:17:10.957657Z] Running install step. 03:17:10 INFO - Running pre-action listener: _resource_record_pre_action 03:17:10 INFO - Running main action method: install 03:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:11 INFO - Reading from file tmpfile_stdout 03:17:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:11 INFO - Reading from file tmpfile_stdout 03:17:11 INFO - Output received: 03:17:11 INFO - Usage: mozinstall [options] installer 03:17:11 INFO - Options: 03:17:11 INFO - -h, --help show this help message and exit 03:17:11 INFO - -d DEST, --destination=DEST 03:17:11 INFO - Directory to install application into. [default: 03:17:11 INFO - "/builds/slave/test"] 03:17:11 INFO - --app=APP Application being installed. [default: firefox] 03:17:11 INFO - mkdir: /builds/slave/test/build/application 03:17:11 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'] 03:17:11 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 03:17:27 INFO - Reading from file tmpfile_stdout 03:17:27 INFO - Output received: 03:17:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:27 INFO - Running post-action listener: _resource_record_post_action 03:17:27 INFO - [mozharness: 2016-09-19 10:17:27.311237Z] Finished install step (success) 03:17:27 INFO - [mozharness: 2016-09-19 10:17:27.311482Z] Running run-tests step. 03:17:27 INFO - Running pre-action listener: _resource_record_pre_action 03:17:27 INFO - Running main action method: run_tests 03:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:27 INFO - Python 2.7.3 03:17:27 INFO - Return code: 0 03:17:27 INFO - grabbing minidump binary from tooltool 03:17: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')]} 03:17:27 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 0xdb9f60>, '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 0xdbad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdbb1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:27 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 03:17:27 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 03:17:27 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 03:17:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:27 INFO - Return code: 0 03:17:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 03:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 03:17:27 INFO - Using env: {'DISPLAY': ':0', 03:17:27 INFO - 'HOME': '/home/cltbld', 03:17:27 INFO - 'LANG': 'en_US.UTF-8', 03:17:27 INFO - 'LANGUAGE': 'en_US:en', 03:17:27 INFO - 'LOGNAME': 'cltbld', 03:17:27 INFO - 'MAIL': '/var/mail/cltbld', 03:17:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:27 INFO - 'MOZ_NO_REMOTE': '1', 03:17:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:27 INFO - 'NO_EM_RESTART': '1', 03:17:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:27 INFO - 'PWD': '/builds/slave/test', 03:17:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:27 INFO - 'SHELL': '/bin/bash', 03:17:27 INFO - 'SHLVL': '1', 03:17:27 INFO - 'TERM': 'linux', 03:17:27 INFO - 'TMOUT': '86400', 03:17:27 INFO - 'USER': 'cltbld', 03:17:27 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642', 03:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:27 INFO - '_': '/tools/buildbot/bin/python'} 03:17:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 03:17:27 INFO - mozversion application_buildid: 20160919023017 03:17:27 INFO - mozversion application_changeset: 651b30ec6c5c4820559027fbda9bdc15fa504930 03:17:27 INFO - mozversion application_display_name: Nightly 03:17:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:27 INFO - mozversion application_name: Firefox 03:17:27 INFO - mozversion application_remotingname: firefox 03:17:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:17:27 INFO - mozversion application_vendor: Mozilla 03:17:27 INFO - mozversion application_version: 51.0a1 03:17:27 INFO - mozversion platform_buildid: 20160919023017 03:17:27 INFO - mozversion platform_changeset: 651b30ec6c5c4820559027fbda9bdc15fa504930 03:17:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:17:27 INFO - mozversion platform_version: 51.0a1 03:17:27 INFO - using testdate: 1474280247 03:17:27 INFO - actual date: 1474280247 03:17:27 INFO - starting webserver on 'localhost:58359' 03:17:27 INFO - SUITE-START | Running 2 tests 03:17:27 INFO - TEST-START | dromaeo_css 03:17:27 INFO - operating with platform_type : linux_ 03:17:27 INFO - Initialising browser for dromaeo_css test... 03:17:27 INFO - TEST-INFO | started process 3183 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfHYiDT/profile http://localhost:58359/getInfo.html) 03:17:32 INFO - PROCESS | 3183 | __metrics Screen width/height:1600/1200 03:17:32 INFO - PROCESS | 3183 | colorDepth:24 03:17:32 INFO - PROCESS | 3183 | Browser inner width/height: 1024/697 03:17:32 INFO - PROCESS | 3183 | __metrics 03:17:33 INFO - PROCESS | 3183 | 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" 03:17:33 INFO - PROCESS | 3183 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:17:33 INFO - PROCESS | 3183 | console.error: PushService: 03:17:33 INFO - PROCESS | 3183 | stateChangeProcessEnqueue: Error transitioning state 03:17:33 INFO - PROCESS | 3183 | UnknownError 03:17:34 INFO - TEST-INFO | 3183: exit 0 03:17:34 INFO - Browser initialized. 03:17:34 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:34 INFO - TEST-INFO | started process 3265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfHYiDT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:46 INFO - PROCESS | 3265 | RSS: Main: 175009792 03:17:46 INFO - PROCESS | 3265 | 03:17:53 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:18:09 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:18:25 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.05009 seconds. 03:18:33 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:18:35 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:18:37 INFO - PROCESS | 3265 | 1474280317673 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] 03:18:48 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 03:18:54 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:19:07 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:19:09 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 03:19:11 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 03:19:13 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 03:19:18 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 03:19:53 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:19:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.18339 seconds. 03:20:11 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:20:13 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 03:20:15 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:20:17 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:20:19 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 03:20:21 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 03:20:25 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:20:27 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:20:34 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:20:40 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 03:20:43 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:20:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:20:48 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.48341 seconds. 03:20:49 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 03:20:51 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58359/tests/dromaeo/cssquery-ext.html) 03:20:52 INFO - PROCESS | 3265 | RSS: Main: 289759232 03:20:52 INFO - PROCESS | 3265 | 03:20:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 03:21:07 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:21:11 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:21:25 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:21:41 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 03:21:43 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:21:49 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:21:51 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:21:53 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:21:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:22:02 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:22:06 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:22:09 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:22:20 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:22:22 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 03:22:36 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:22:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:22:44 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:22:48 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:22:54 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 03:22:56 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:23:00 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:23:06 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 03:23:08 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 03:23:10 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:23:20 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 03:23:30 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:23:37 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:23:39 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:23:43 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:23:55 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-ext.html (next: http://localhost:58359/tests/dromaeo/cssquery-jquery.html) 03:23:55 INFO - PROCESS | 3265 | RSS: Main: 274403328 03:23:55 INFO - PROCESS | 3265 | 03:24:01 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 03:24:04 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:24:16 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:24:24 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:24:39 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:24:59 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:25:05 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:25:07 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 03:25:25 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:25:44 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 03:25:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:25:47 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:25:51 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 03:25:53 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:25:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:26:31 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:26:35 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:26:37 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 03:26:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 03:26:44 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:26:46 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:26:50 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:26:54 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:26:56 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:26:58 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 03:26:58 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58359/tests/dromaeo/cssquery-mootools.html) 03:26:58 INFO - PROCESS | 3265 | RSS: Main: 257945600 03:26:58 INFO - PROCESS | 3265 | 03:27:13 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:27:41 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:27:43 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:27:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:27:46 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 2.0001 seconds. 03:27:49 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01054 seconds. 03:27:51 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:27:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:28:03 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:28:10 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:28:20 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:28:30 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 03:28:34 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:28:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:28:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:29:00 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:29:10 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:29:12 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:29:39 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:29:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:29:44 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:29:46 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:29:56 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:30:01 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58359/tests/dromaeo/cssquery-prototype.html) 03:30:01 INFO - PROCESS | 3265 | RSS: Main: 220168192 03:30:01 INFO - PROCESS | 3265 | 03:30:18 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 03:30:31 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:30:52 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:31:07 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:31:09 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:31:12 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:31:15 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:31:25 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:31:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:31:50 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:31:55 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 03:32:01 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:32:04 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:32:06 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:32:08 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:32:10 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:32:13 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:32:24 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:32:26 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:32:29 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:32:30 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:32:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:32:45 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:33:00 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 03:33:04 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58359/tests/dromaeo/cssquery-yui.html) 03:33:05 INFO - PROCESS | 3265 | RSS: Main: 218787840 03:33:05 INFO - PROCESS | 3265 | 03:33:10 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 03:33:17 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 03:33:30 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:33:42 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:33:50 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 03:33:52 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:33:54 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:33:58 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:34:07 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:34:08 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:34:12 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:34:14 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:34:18 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:34:22 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:34:38 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 03:35:05 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:35:11 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:35:13 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:35:33 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 03:35:43 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 03:35:48 INFO - PROCESS | 3265 | [GFX1-]: Refresh driver waiting for the compositor for 1.0501 seconds. 03:36:08 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:58359/tests/dromaeo/cssquery-yui.html (next: http://localhost:58359/tests/dromaeo/cssquery-dojo.html) 03:36:08 INFO - PROCESS | 3265 | RSS: Main: 307683328 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | __start_tp_report 03:36:08 INFO - PROCESS | 3265 | _x_x_mozilla_page_load 03:36:08 INFO - PROCESS | 3265 | _x_x_mozilla_page_load_details 03:36:08 INFO - PROCESS | 3265 | |i|pagename|runs| 03:36:08 INFO - PROCESS | 3265 | |0;dojo.html;2581.3933725574493;2603.193010166847;2637.868106594671;2640.775534079603;2652.005497938272;4553.2942393928915;4620.976895115528;4764.976175119124;4788.449328327239;4796.952030479694;4638.953610463895;4672.836450724217;4685.83599574015;4714.622830173586;4723.622110231173;4263.3178691409385;4282.807273672676;4298.140371925611;4300.849470268541;4365.126974605076;4319.48166220054;4368.650507959355;4399.538048504909;4403.207422663919;4452.287633978564;4222.050038741289;4268.55180206077;4337.457817772778;4392.626626736731;4426.313921342197;4306.181825453154;4335.479742430928;4358.7166834156;4387.868363949055;4449.955500444987;4333.783310834446;4342.1532801103795;4342.174986752539;4346.978460061883;4396.692231543793;4774.594159496447;4780.1873681474235;4806.951930480685;4808.08646357191;4858.5384388483035;4849.466558678542;4898.975505122487;4904.6321525885705;4928.7535623219055;4965.180745177042;4977;4990.700557966534;4994.750262486898;4995.750212489397;5046.84859454217;4695.2252878275385;4700.130475861976;4703.2004559225015;4748.762561871893;4761.190597598416;4995.275685025651;5044.016416798762;5084.9745751271;5126.820561280338;5135.589152867761;4912.6561140719805;4913.091078150482;4917.1149193144865;4968.751562421937;4978.975105124451;5032.547070763649;5052.090623687772;5052.216906379517;5059.241113832954;5121.615878809104;4814.662973591885;4967.652264341461;4975.875603109951;5011;5026.4973502649445;4772.809087636525;4870.732109733999;4890.975545122252;4891.192953162688;4894.094592500399;4155.688323375698;4161.3549899765585;4177.791110444466;4222.0500387412585;4236.428082208862;3642.0348607619003;3663.413853783382;3683.447482877536;3685.760425572329;3718.2377612589353;4293.162833247549;4294.26997410441;4294.527601963781;4306.4832220133785;4347.673924455678;4842.903141937141;4881.048195601824;4998.850034498971;5007.924881126786;5017.974910125499;4331.7834108294455;4341.630961368319;4350.1734670412525;4370.628496577827;4395.384646149541;4538.319252112209;4547.704399214041;4558.997020655451;4560.475545312265;4590.816367345269;4601.769911504344;4651.790669419816;4711.340412342343;4746.050789841976;4752.572268495852;5067.163917953497;5080.669756465818;5084.1102807009365;5128.564072053917;5146;4736.526347365374;4742.620590352833;4747.7863496143445;4750.287456881495;4811.735354555533;4819.566239038503;4853.563179313879;4977.402711674622;4984.647644724003;5042.39491261051;5077.847664570068;5117.74411279442;5121.359830021247;5190.532852043334;5192.74036298176;4972.254161875748;4976.2038073908;4977.651564390603;4978.4523702393435;5040.495950405077;4750.453697824656;4786.712797232177;4966.751662416792;4972.254600157458;4974.12952733263;4752;4780.737059461763;4812.374391329245;4828.179209534317;4879.536444037811;4872.074305881871;4888.266759986031;4904.068227036852;4907.975460122677;4966.801327946841;4645;4693.765311734332;4695.154872123051;4708.741019243975;4813.278008298783 03:36:08 INFO - PROCESS | 3265 | |1;ext.html;566.5241197394189;569.8432930943987;581.0789898011651;581.7294957844601;583.5244275915128;28097.29756756082;39302.606973930255;40395.19209615806;40396;43979.78010109953;39824.40263396044;40296.19407611846;40310.19379612406;40335.596644033554;40357.59642403575;7524.097108347006;7657.693692252304;7665.770026899174;7674.270944260286;7678.808029799272;7470.626468676572;7532.9246707532775;7558.811029724246;7559.69761209551;7562.432817538706;7546.7358642447525;7547.509411888237;7578.886316705254;7590.810229744246;7592.658330375147;7549.547027178387;7558.886616700726;7592.430567707444;7592.886106708432;7593.164751877289;7458.813529661747;7521.548707077593;7526.962365188139;7527.5859827709455;7534.698612055511;39130.21739565221;39352.212955740724;39369;39449.60550394488;39495.21009579821;2687.6640419947507;2700.0954680182163;2738.8493632850186;2775.7918156138167;2795.8042936994216;28757.137285881458;28847.374761921434;28946;29059.273518162216;29289.56065659017;3232.69289417505;3266.657001014883;3303.8678452861855;3316.5522654441584;3326.3513614845115;243729;245497;245721.99999999822;246401;247417;52134.21798673023;52245;52495.47504524982;52516.21225681618;52572.474275257526;32359.822428411342;32885.342293154004;32913.83543082269;35349;35489;13515.189088654515;13668.248246346546;13704.931475342759;13706.246156461291;13711.931440342934;10109.443980581138;10198.133158681596;10218.744531386626;10221.897781022244;10225.130863876506;13977;14001.439942402394;14053.367598458093;14135.78796318056;14161.221132837587;6627.668616569153;6653.168353955755;6737.157855268091;6911.550749201286;6927.79216623492;766.7738017284914;854.8247609240091;874.0254616102999;900.2123142250531;979.982073498664;26725.465490690076;27792.58311125335;27967.300817479725;28344;28500.429991400055;884.5444596033049;912.3248795891126;912.7854954085784;937.2689302344182;982.6118683120128;14149.29253537319;14890.925545372203;14897.925510372379;15013.69972600542;15121.243937803067;360487;365005;365158;365206;365584;49578;50078.74960625174;50164.24753628699;50209;50242.49757502378;1041.4896700616644;1069.4332004037872;1111.838783376422;1116.609186784619;1125.1280257800106;27457.31356716098;28458;29182.708172918847;29544.261393465335;29553.704462955095;42118.36822447604;42667.14665706606;42668.78665606652;42723.35914961216;42728;299324;299364;301251;301282;301848;22001.889990549946;22444.418891826997;23803.64294535618;24552.508949820545;24756.876215618806;40696.593034070465;40904.59095409127;40998.79500602478;41257.793711031256;42026;35349.1162720934;35491.467627986116;35537.82231088828;35769.46345804867;35957.28085438224;34921.82539087288;35023;35204.47193292154;35276.647233527336;35395.46906796349;34792.30415391627;35156.296874062886;35433.29133417266;36053.81973090118;36074.63925360818;30016.699833001392;31972.520412194302;32045.67954320427;32150.35699286048;32188.678113218568;24620;24625;24935.37661558403;24966.251012469656;25271.36821579475 03:36:08 INFO - PROCESS | 3265 | |2;jquery.html;347.168531367375;366.5473140671272;380.82101412336203;381.4278582126809;396.7778044295195;12118.939405302972;12364.938175309122;13642.727145457085;16925;17150.142492875337;15373.38506459743;15466.149361785097;15552;16145.031298122121;16246.83753162468;4829.082474329884;4923.950760492394;5063.518965698253;5092.261622064799;5095.388553373582;3574.4817001534893;5023.748812559375;5110.105731496992;5120.615953803461;5120.948790512084;3730.496382988289;5499.257600223978;5742.224799652056;5755.165501002352;5854.443827836349;5697.77208911643;5842.678652674102;5854.502367298764;5855.238818953509;5947.1376650385455;5266.8683282917855;5268.236105764643;5269.920951185735;5270.6837589744355;5273.419923808378;17578.945263284244;18308.54228644281;18915.90542047281;19008.809911900844;19682.409527714193;1647.6375197456541;1764.1179410294853;1849.140149830322;1862.10618902926;1888.8583356248337;8931.598078086501;9449.385789923634;9498.382605130644;11127.610533631285;13228.933855330759;1703.5059832648517;1710.0509217384347;1718.6219031812982;1778.484239570523;1794.0760508338217;63849.680751595944;70680.2931970684;70821.2917870825;71435.28564714287;72158;9161.404508706913;9237.12247336502;9362.672306469241;9758.658446954465;9927.950360248296;12222.449989750483;12225;12235.449404776806;12240.81638775419;12244.265344079384;6148.477379422709;6333.366663333722;6350.174172493196;6701.229358623723;6727.226368967631;5945.227120474311;6144.477719393813;6336.398042185985;6339.809805705836;6365.809025729235;4090.1615168890307;4169.666426685858;4179.226843034048;4215.810288537024;4370.169667763115;1638.6476907464953;1644.868410527155;1726.956826079358;1763.8588912886937;1837.255911355903;645.4320198225531;811.4238890387891;823.0658202939707;827.2430725885868;879.7360791762445;15735.921320393554;16044.43844465438;16467.423640172776;16684.499465015826;17136.143192840307;782.1787123520395;804.284187073505;817.2522142239784;836.1011912194415;893.4683863101444;8537.914620853837;9104.089591040844;9117.726468205965;9230.215431688383;9258.72223833286;379360;391030;391053;391870;392441;80228;80493;82010.17989820025;83042;83423.5828820852;909.508865212791;917.7843206846386;939.9201067909304;949.7625593601599;951.3911096897991;14134.78797818013;14263.572092837232;14396;14469.131852088909;15045.473408430653;33015.66984330126;33524.49713254352;34282.828585856914;34401.31197376089;34520;45543.31685024794;46907.53092469032;47405.762971186305;47509.287360688926;47564;6964.268751781193;7040.628574270146;7106.218315985341;7207.927920720936;7446.478746487694;10714.9105924344;11068.810276621074;11146.442677865913;11160.051395631463;11262.83105753398;6825.1127353443735;9061.637534498546;9414.435133891988;9806.068423499755;9879.160271377013;8981.461112333282;9225.769355766159;9501.714948551555;9687.370320929118;9820.312578119749;9722.805543889224;9740.75648108803;9755.512224388895;10232.23258255619;10609.469526523488;6989.161300643752;7010.894836577558;7339.119305683352;7349.1915889253205;7368.300011498899;8661.13388661129;9539.713808585753;9667.178289845442;9964.701058968243;9975.950120249352 03:36:08 INFO - PROCESS | 3265 | |3;mootools.html;1351.8715722006407;1394.26800929512;1451.920144392058;1628.9918550407247;1667.7081510735618;6837.4530037597;7054.153501579817;7339.926600733991;7852.136265010851;7855.410844186687;7383.372413344859;7643.732469363574;7644.847103057936;7872.488288261274;7872.7638170854825;2187.8687278763327;2203.22886989554;2217.268301460514;2219.7891200335944;2221.1559607349186;1801.9099045047694;1859.4142845003848;2066.5866826634656;2142.2287976328507;2165.0473791531754;2140.978590214101;2195.5279614882797;2205.3494219205295;2206.161658569741;2206.9668954965687;2072.0882811562865;2084.291340944079;2089.707440958267;2127.5000374911883;2130.1479408236673;2097.318371529259;2107.7365329333834;2114.0381126587367;2116.43914362694;2117.962198522728;7654.19630938749;7678.769636910902;7741.148473667892;7744.922550774477;7760.30157285847;4384.9561504384865;4499.235130027871;4518.593326600589;4518.954810451886;4519.435070616173;2785.136607651614;2913.621229240185;2954.9113526594238;2957.7338039576543;2982.8806847726064;948.7390967483929;952.6284748947916;968.4625033106623;971.5773638467218;980.9215262778961;10591.94704026475;10607.575696972111;10639;10639.946800265949;10647.57409703611;4080.1227736036894;4175.603317684815;4228.323468245066;4307.0955099429475;4322.848700295474;8279.668813247523;8395.832083358298;8414.6213420395;8421.368397370221;8422.28410585095;4447.666425018137;4451.59935605797;4452.9554704453185;4476.664250181249;4483.574060464251;4087.8978025549004;4102.815373308208;4157.023099571598;4157.91684166315;4161.167766446722;3211.277462570903;3252.4145653782075;3319;3335.1328654549993;3344.0803778960494;952.6856137474755;952.7856232347805;957.4398976598768;957.8419560772395;981.1071924548769;533.6691251424141;688.8450098727747;737.6791095873205;755.4183278875236;766.061574571157;4514.232580461329;4812.302216178635;4822.831200908022;5034.79860805571;5114.3862736471865;718.3104219948804;738.1732459645232;748.5433885329944;773.2963003666637;785.1441928298068;3106.223444138965;3158.289384888428;3186.1715953851703;3186.8087914725193;3189.1389324882152;9308.348415610977;9805.80388392228;9840.261980351503;9841.212702983745;9845.852312215322;9676.371035882645;9678.516074196263;9698.224142068617;9716.028397160228;9716.854247186298;7114.786556403316;7257;7270.8182295441975;7275.199728029913;7279;4614.215583350771;4645.651576131736;4737.455192652889;4747.216709242936;4837.05677392905;8448.788780280542;8581.35639827003;8796.956015219883;8801.823963520821;8826.91173088261;8274.834503310021;8428.072911979554;8529.360297977553;8552.401331906707;8581.184787445183;6277.403646653561;6462.450691691259;6472.288048314776;6477.060826180272;6481.319461456667;7064.646767661699;7107.573545587281;7109.4667899908745;7116.537425067354;7134.1795693495915;6414.134091897534;6495.642739649365;6534.640594767334;6591.670416479061;6607.80176594703;6441.323661015714;6456.418922297016;6472.644004579794;6485.416312531895;6510.869782604226;5843.328017278068;5942.64344139353;5984.491318237999;5987.34139244674;5993.910091348546;4214.431051808089;4225.598568136023;4273.764942928169;4294.334378171388;4369.71596846204;4215.683823713295;4311.935320970125;4332.956670433381;4364.672649551233;4403.5376285489565 03:36:08 INFO - PROCESS | 3265 | |4;prototype.html;297.5640686394432;304.32743636563197;307.42051233424996;313.8807253243767;314.8962885087767;4795.640326975474;4804.879878003043;4806.759662016898;4814.54261845125;4857.368542089528;4766.666333356667;4776.259679749644;4780.235162374021;4784.760761961906;4832.275158726193;3592.1917568547065;3674.6325367463305;3685.94471082934;3737.252549490113;3764.4353346997896;3765.1151979284846;3766.1902690921447;3767.660910518053;3780.2250538639514;3795.4496597993275;3739.196072844338;3749.9062523436996;3749.9437508437395;3754.155315054118;3769.4345848122725;3551.8579256829694;3588.6052534221217;3676.540432445931;3732.3841566141555;3750.081230098616;3276.950845737316;3292.341531693647;3297.3405318936307;3323.833808309599;3335.6497567755277;4572.977135114336;4574.817007319703;4576.711644177911;4590.839320623795;4593.908121837578;4176.540580536139;4219.767631330771;4272.1028583997395;4272.9359059614135;4286.7856607169515;4891.69810377997;4900.681455705368;4915.37027690184;4925.7783399747095;4943.802247910079;3604.6122242936394;3618.222082252328;3622.601513833476;3634.7819130852236;3655.3785856404475;4933.28467372229;5133.974330128362;5190.377154741455;5197.766100525486;5203.869903252411;3644.963550364462;3670.5778835433734;3672.7979961102396;3676.9632303677154;3691.409374500121;3722.1253005543676;3744.700423966076;3745.7939813309986;3751.2872554214614;3753.680937120321;4212.915741685149;4244.427002354642;4253.915581365109;4254.978805086756;4280.957190428118;4774.3077253798;4881.536254055859;4913.732367975328;4958.256261560723;4972.676776009548;1302.6027061746258;1310.4823594680047;1310.678883673487;1316.8946484281234;1316.973660526784;835.774340927946;855.4354126276628;865.1478293880523;865.3769286113987;874.7054359547905;3975.0658595230097;4224.5353011168745;4281.49532523471;4353.36876152956;4439.445069366329;5172.844814655567;5173.163526947955;5178.249153872667;5181.585473162213;5185.948140518546;4433.780710304627;4435.179491794029;4445.355423463579;4445.799939002753;4449.888752781207;2622.3313055171193;2696.541587930017;2713.552263876478;2729.8362098274165;2743.82165159264;4846.660733748604;4860.246661767432;4862.520114741436;4863.124637565202;4866.562009419098;5250.369955605352;5261.921071183935;5306.381553626104;5306.92039619406;5319.1223448131395;4745.6440766942615;4797.544233297831;4842.297866809293;4844.370231869834;4874.926876096862;5122.487751224848;5129.230615407719;5147.3308469898675;5158.8968220636125;5158.97420512895;5020.548150666312;5026.924596131134;5027.547520723152;5042.621803364689;5044.4451110377095;4614.653900957374;4781.804548862784;4826.710397376169;4856.271559266138;4861.295112208781;3726.366517691945;3734.234481931252;3748.812559372075;3750.324941510445;3769.5099637048106;5158.38099428071;5173.7671804769625;5201.479852014768;5212.635115541876;5213.869653258699;4782.736949467673;5024.221245706921;5041.798328066836;5043.092243396077;5047.747612619428;4990.8752281193265;4991;5015.548600625962;5020.47285035066;5020.673656212334;4960.404751429707;4973.751312434291;4974.602031837517;4978.32792572998;4999.975000124976;4800.855974320776;4966.329545511309;4969.701817890938;4998.750062496788;5035.471275516018;4825.710457372569;4831.202851529599;4957.900841983213;4983.676061056165;4986.50134986513 03:36:08 INFO - PROCESS | 3265 | |5;yui.html;534.2440446767822;551.589066145721;552.8037546670935;573.0802062689384;581.6680739281359;12358.691032724175;13610.863891361083;13614.659633509167;13622.182669039865;13625;11647.126465515099;12217.694557636041;12473.189242699218;12562.371881405916;12575.119741618068;741.7070257248397;761.1437133225122;762.8436170585045;763.2748888555866;764.2701220334567;578.6788332475475;579.7651950959847;744.6314074533086;752.2214507984237;765.9885101723479;767.6583920155533;768.051456451285;768.5734417398342;770.2220757035378;771.8842173673937;759.8024513626442;760.8744557148092;763.904511936008;767.8656235158825;770.9845803083934;458.3377020205804;463.3166080031926;473.2924278204072;477.6537010667273;562.2830890614458;12017.519299228108;12600.4329805159;12614.432350544248;12618.369081545887;12620.55828046014;10312.535935882903;10653.360798352047;10764.461776911201;11186.720331991684;11225.831612525819;9090.409123407024;9768.755781105458;10454.372737635766;10492.21308401873;10515.421651809147;3877.7673339599714;3913.1391093959282;3928.508936382952;4000.559938406802;4083.4283200351974;58113.709431453;58813;58882.11676824851;59208.70395647994;59582.70208648972;8833.823323533494;8838.602262898185;8904.174644197923;8991.460512369278;9085;16075.276612552463;16188;16448.095354755602;16513.256903439375;16741.581460463585;6098.69506524672;6218;6307.274663413675;6455.289918109004;6540.8364790880605;3346.899593012165;3355.932881342408;3379.7465190110347;3384.661533846596;3486.651334866493;2483.056438553338;2550.7194208637034;2558.5650439425344;2562.0392352867675;2565.486902619446;457.425930457274;457.8855286178455;458.17985805408614;477.6895018238173;483.845169545749;406.8392984770999;412.2888018168695;415.5346012465997;418.0593664255425;425.8360531195512;6155.261368635793;6381.074744162163;6451.2903580607035;6494.870102597922;6928.237893831674;317.78867053409226;336.737344871001;359.68886912820744;376.94534292527976;386.7370188272002;3113.641931177898;3323.80057196569;3347.3807345641135;3361.3445378151514;3388.542546756206;99059.50470247603;101503;104189.47905260425;104459.477702611;106062;89754;92618;94401;94483;96216;79267.60366198247;81174.59412702899;81349.59325203454;81449;81530.59234703789;6163.845903852261;6166.105914642262;6207.96896015517;6361.522885783492;6457.192850893638;46925.2961205589;47194.40430776203;47337.763311183226;47792.7610361946;49061.2640810381;38139.80930095332;41663;42915;43045.784771075945;52768.736156320505;8613.889597048763;8691.95654021726;8821.558922053744;8997.910020899708;9167.891943080826;9875.506224688881;9952.900470995197;9960.356541170915;9986.101250887456;10014.64948726791;9691.9030809691;9742.415455072718;10017.599296028076;10195.643152489627;10495.842562361724;8882.333824963283;9525.285603579621;9629.229661627192;9629.759256018655;9661.806763864824;9163.404378715362;9330.626774929198;9346.299027573095;9657.855132172883;9670.903290967;7848.607569621382;7925.365970722213;8263.628136733982;8600.13998600135;8633;7800.570968596781;7890.171977994701;7950.3639708822;8031.839363212586;8064.435489515678 03:36:08 INFO - PROCESS | 3265 | __end_tp_report 03:36:08 INFO - PROCESS | 3265 | __start_cc_report 03:36:08 INFO - PROCESS | 3265 | _x_x_mozilla_cycle_collect,991 03:36:08 INFO - PROCESS | 3265 | __end_cc_report 03:36:08 INFO - PROCESS | 3265 | __startTimestamp1474281368445__endTimestamp 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | ------- Summary: start ------- 03:36:08 INFO - PROCESS | 3265 | Number of tests: 6 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#0] dojo.html Cycles:165 Average:4638.62 Median:4764.98 stddev:484.77 (10.2%) stddev-sans-first:458.61 03:36:08 INFO - PROCESS | 3265 | Values: 2581.4 2603.2 2637.9 2640.8 2652.0 4553.3 4621.0 4765.0 4788.4 4797.0 4639.0 4672.8 4685.8 4714.6 4723.6 4263.3 4282.8 4298.1 4300.8 4365.1 4319.5 4368.7 4399.5 4403.2 4452.3 4222.1 4268.6 4337.5 4392.6 4426.3 4306.2 4335.5 4358.7 4387.9 4450.0 4333.8 4342.2 4342.2 4347.0 4396.7 4774.6 4780.2 4807.0 4808.1 4858.5 4849.5 4899.0 4904.6 4928.8 4965.2 4977.0 4990.7 4994.8 4995.8 5046.8 4695.2 4700.1 4703.2 4748.8 4761.2 4995.3 5044.0 5085.0 5126.8 5135.6 4912.7 4913.1 4917.1 4968.8 4979.0 5032.5 5052.1 5052.2 5059.2 5121.6 4814.7 4967.7 4975.9 5011.0 5026.5 4772.8 4870.7 4891.0 4891.2 4894.1 4155.7 4161.4 4177.8 4222.1 4236.4 3642.0 3663.4 3683.4 3685.8 3718.2 4293.2 4294.3 4294.5 4306.5 4347.7 4842.9 4881.0 4998.9 5007.9 5018.0 4331.8 4341.6 4350.2 4370.6 4395.4 4538.3 4547.7 4559.0 4560.5 4590.8 4601.8 4651.8 4711.3 4746.1 4752.6 5067.2 5080.7 5084.1 5128.6 5146.0 4736.5 4742.6 4747.8 4750.3 4811.7 4819.6 4853.6 4977.4 4984.6 5042.4 5077.8 5117.7 5121.4 5190.5 5192.7 4972.3 4976.2 4977.7 4978.5 5040.5 4750.5 4786.7 4966.8 4972.3 4974.1 4752.0 4780.7 4812.4 4828.2 4879.5 4872.1 4888.3 4904.1 4908.0 4966.8 4645.0 4693.8 4695.2 4708.7 4813.3 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#1] ext.html Cycles:180 Average:45210.20 Median:35514.64 stddev:80834.37 (227.6%) stddev-sans-first:80991.63 03:36:08 INFO - PROCESS | 3265 | Values: 566.5 569.8 581.1 581.7 583.5 28097.3 39302.6 40395.2 40396.0 43979.8 39824.4 40296.2 40310.2 40335.6 40357.6 7524.1 7657.7 7665.8 7674.3 7678.8 7470.6 7532.9 7558.8 7559.7 7562.4 7546.7 7547.5 7578.9 7590.8 7592.7 7549.5 7558.9 7592.4 7592.9 7593.2 7458.8 7521.5 7527.0 7527.6 7534.7 39130.2 39352.2 39369.0 39449.6 39495.2 2687.7 2700.1 2738.8 2775.8 2795.8 28757.1 28847.4 28946.0 29059.3 29289.6 3232.7 3266.7 3303.9 3316.6 3326.4 243729.0 245497.0 245722.0 246401.0 247417.0 52134.2 52245.0 52495.5 52516.2 52572.5 32359.8 32885.3 32913.8 35349.0 35489.0 13515.2 13668.2 13704.9 13706.2 13711.9 10109.4 10198.1 10218.7 10221.9 10225.1 13977.0 14001.4 14053.4 14135.8 14161.2 6627.7 6653.2 6737.2 6911.6 6927.8 766.8 854.8 874.0 900.2 980.0 26725.5 27792.6 27967.3 28344.0 28500.4 884.5 912.3 912.8 937.3 982.6 14149.3 14890.9 14897.9 15013.7 15121.2 360487.0 365005.0 365158.0 365206.0 365584.0 49578.0 50078.7 50164.2 50209.0 50242.5 1041.5 1069.4 1111.8 1116.6 1125.1 27457.3 28458.0 29182.7 29544.3 29553.7 42118.4 42667.1 42668.8 42723.4 42728.0 299324.0 299364.0 301251.0 301282.0 301848.0 22001.9 22444.4 23803.6 24552.5 24756.9 40696.6 40904.6 40998.8 41257.8 42026.0 35349.1 35491.5 35537.8 35769.5 35957.3 34921.8 35023.0 35204.5 35276.6 35395.5 34792.3 35156.3 35433.3 36053.8 36074.6 30016.7 31972.5 32045.7 32150.4 32188.7 24620.0 24625.0 24935.4 24966.3 25271.4 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#2] jquery.html Cycles:180 Average:23818.13 Median:5267.55 stddev:64414.44 (1222.9%) stddev-sans-first:64571.03 03:36:08 INFO - PROCESS | 3265 | Values: 347.2 366.5 380.8 381.4 396.8 12118.9 12364.9 13642.7 16925.0 17150.1 15373.4 15466.1 15552.0 16145.0 16246.8 4829.1 4924.0 5063.5 5092.3 5095.4 3574.5 5023.7 5110.1 5120.6 5120.9 3730.5 5499.3 5742.2 5755.2 5854.4 5697.8 5842.7 5854.5 5855.2 5947.1 5266.9 5268.2 5269.9 5270.7 5273.4 17578.9 18308.5 18915.9 19008.8 19682.4 1647.6 1764.1 1849.1 1862.1 1888.9 8931.6 9449.4 9498.4 11127.6 13228.9 1703.5 1710.1 1718.6 1778.5 1794.1 63849.7 70680.3 70821.3 71435.3 72158.0 9161.4 9237.1 9362.7 9758.7 9928.0 12222.4 12225.0 12235.4 12240.8 12244.3 6148.5 6333.4 6350.2 6701.2 6727.2 5945.2 6144.5 6336.4 6339.8 6365.8 4090.2 4169.7 4179.2 4215.8 4370.2 1638.6 1644.9 1727.0 1763.9 1837.3 645.4 811.4 823.1 827.2 879.7 15735.9 16044.4 16467.4 16684.5 17136.1 782.2 804.3 817.3 836.1 893.5 8537.9 9104.1 9117.7 9230.2 9258.7 379360.0 391030.0 391053.0 391870.0 392441.0 80228.0 80493.0 82010.2 83042.0 83423.6 909.5 917.8 939.9 949.8 951.4 14134.8 14263.6 14396.0 14469.1 15045.5 33015.7 33524.5 34282.8 34401.3 34520.0 45543.3 46907.5 47405.8 47509.3 47564.0 6964.3 7040.6 7106.2 7207.9 7446.5 10714.9 11068.8 11146.4 11160.1 11262.8 6825.1 9061.6 9414.4 9806.1 9879.2 8981.5 9225.8 9501.7 9687.4 9820.3 9722.8 9740.8 9755.5 10232.2 10609.5 6989.2 7010.9 7339.1 7349.2 7368.3 8661.1 9539.7 9667.2 9964.7 9976.0 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#3] mootools.html Cycles:180 Average:4957.62 Median:3188.50 stddev:2832.35 (88.8%) stddev-sans-first:2827.34 03:36:08 INFO - PROCESS | 3265 | Values: 1351.9 1394.3 1451.9 1629.0 1667.7 6837.5 7054.2 7339.9 7852.1 7855.4 7383.4 7643.7 7644.8 7872.5 7872.8 2187.9 2203.2 2217.3 2219.8 2221.2 1801.9 1859.4 2066.6 2142.2 2165.0 2141.0 2195.5 2205.3 2206.2 2207.0 2072.1 2084.3 2089.7 2127.5 2130.1 2097.3 2107.7 2114.0 2116.4 2118.0 7654.2 7678.8 7741.1 7744.9 7760.3 4385.0 4499.2 4518.6 4519.0 4519.4 2785.1 2913.6 2954.9 2957.7 2982.9 948.7 952.6 968.5 971.6 980.9 10591.9 10607.6 10639.0 10639.9 10647.6 4080.1 4175.6 4228.3 4307.1 4322.8 8279.7 8395.8 8414.6 8421.4 8422.3 4447.7 4451.6 4453.0 4476.7 4483.6 4087.9 4102.8 4157.0 4157.9 4161.2 3211.3 3252.4 3319.0 3335.1 3344.1 952.7 952.8 957.4 957.8 981.1 533.7 688.8 737.7 755.4 766.1 4514.2 4812.3 4822.8 5034.8 5114.4 718.3 738.2 748.5 773.3 785.1 3106.2 3158.3 3186.2 3186.8 3189.1 9308.3 9805.8 9840.3 9841.2 9845.9 9676.4 9678.5 9698.2 9716.0 9716.9 7114.8 7257.0 7270.8 7275.2 7279.0 4614.2 4645.7 4737.5 4747.2 4837.1 8448.8 8581.4 8797.0 8801.8 8826.9 8274.8 8428.1 8529.4 8552.4 8581.2 6277.4 6462.5 6472.3 6477.1 6481.3 7064.6 7107.6 7109.5 7116.5 7134.2 6414.1 6495.6 6534.6 6591.7 6607.8 6441.3 6456.4 6472.6 6485.4 6510.9 5843.3 5942.6 5984.5 5987.3 5993.9 4214.4 4225.6 4273.8 4294.3 4369.7 4215.7 4311.9 4333.0 4364.7 4403.5 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#4] prototype.html Cycles:180 Average:4156.23 Median:4781.02 stddev:1197.33 (25.0%) stddev-sans-first:1165.14 03:36:08 INFO - PROCESS | 3265 | Values: 297.6 304.3 307.4 313.9 314.9 4795.6 4804.9 4806.8 4814.5 4857.4 4766.7 4776.3 4780.2 4784.8 4832.3 3592.2 3674.6 3685.9 3737.3 3764.4 3765.1 3766.2 3767.7 3780.2 3795.4 3739.2 3749.9 3749.9 3754.2 3769.4 3551.9 3588.6 3676.5 3732.4 3750.1 3277.0 3292.3 3297.3 3323.8 3335.6 4573.0 4574.8 4576.7 4590.8 4593.9 4176.5 4219.8 4272.1 4272.9 4286.8 4891.7 4900.7 4915.4 4925.8 4943.8 3604.6 3618.2 3622.6 3634.8 3655.4 4933.3 5134.0 5190.4 5197.8 5203.9 3645.0 3670.6 3672.8 3677.0 3691.4 3722.1 3744.7 3745.8 3751.3 3753.7 4212.9 4244.4 4253.9 4255.0 4281.0 4774.3 4881.5 4913.7 4958.3 4972.7 1302.6 1310.5 1310.7 1316.9 1317.0 835.8 855.4 865.1 865.4 874.7 3975.1 4224.5 4281.5 4353.4 4439.4 5172.8 5173.2 5178.2 5181.6 5185.9 4433.8 4435.2 4445.4 4445.8 4449.9 2622.3 2696.5 2713.6 2729.8 2743.8 4846.7 4860.2 4862.5 4863.1 4866.6 5250.4 5261.9 5306.4 5306.9 5319.1 4745.6 4797.5 4842.3 4844.4 4874.9 5122.5 5129.2 5147.3 5158.9 5159.0 5020.5 5026.9 5027.5 5042.6 5044.4 4614.7 4781.8 4826.7 4856.3 4861.3 3726.4 3734.2 3748.8 3750.3 3769.5 5158.4 5173.8 5201.5 5212.6 5213.9 4782.7 5024.2 5041.8 5043.1 5047.7 4990.9 4991.0 5015.5 5020.5 5020.7 4960.4 4973.8 4974.6 4978.3 5000.0 4800.9 4966.3 4969.7 4998.8 5035.5 4825.7 4831.2 4957.9 4983.7 4986.5 03:36:08 INFO - PROCESS | 3265 | 03:36:08 INFO - PROCESS | 3265 | [#5] yui.html Cycles:180 Average:17045.88 Median:579.22 stddev:26402.67 (4558.3%) stddev-sans-first:26447.63 03:36:08 INFO - PROCESS | 3265 | Values: 534.2 551.6 552.8 573.1 581.7 12358.7 13610.9 13614.7 13622.2 13625.0 11647.1 12217.7 12473.2 12562.4 12575.1 741.7 761.1 762.8 763.3 764.3 578.7 579.8 744.6 752.2 766.0 767.7 768.1 768.6 770.2 771.9 759.8 760.9 763.9 767.9 771.0 458.3 463.3 473.3 477.7 562.3 12017.5 12600.4 12614.4 12618.4 12620.6 10312.5 10653.4 10764.5 11186.7 11225.8 9090.4 9768.8 10454.4 10492.2 10515.4 3877.8 3913.1 3928.5 4000.6 4083.4 58113.7 58813.0 58882.1 59208.7 59582.7 8833.8 8838.6 8904.2 8991.5 9085.0 16075.3 16188.0 16448.1 16513.3 16741.6 6098.7 6218.0 6307.3 6455.3 6540.8 3346.9 3355.9 3379.7 3384.7 3486.7 2483.1 2550.7 2558.6 2562.0 2565.5 457.4 457.9 458.2 477.7 483.8 406.8 412.3 415.5 418.1 425.8 6155.3 6381.1 6451.3 6494.9 6928.2 317.8 336.7 359.7 376.9 386.7 3113.6 3323.8 3347.4 3361.3 3388.5 99059.5 101503.0 104189.5 104459.5 106062.0 89754.0 92618.0 94401.0 94483.0 96216.0 79267.6 81174.6 81349.6 81449.0 81530.6 6163.8 6166.1 6208.0 6361.5 6457.2 46925.3 47194.4 47337.8 47792.8 49061.3 38139.8 41663.0 42915.0 43045.8 52768.7 8613.9 8692.0 8821.6 8997.9 9167.9 9875.5 9952.9 9960.4 9986.1 10014.6 9691.9 9742.4 10017.6 10195.6 10495.8 8882.3 9525.3 9629.2 9629.8 9661.8 9163.4 9330.6 9346.3 9657.9 9670.9 7848.6 7925.4 8263.6 8600.1 8633.0 7800.6 7890.2 7950.4 8031.8 8064.4 03:36:08 INFO - PROCESS | 3265 | -------- Summary: end -------- 03:36:08 INFO - PROCESS | 3265 | 03:36:09 INFO - TEST-INFO | 3265: exit 0 03:36:09 INFO - TEST-OK | dromaeo_css | took 1121890ms 03:36:09 INFO - TEST-START | kraken 03:36:09 INFO - operating with platform_type : linux_ 03:36:09 INFO - Initialising browser for kraken test... 03:36:09 INFO - TEST-INFO | started process 3575 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfOSYUI/profile http://localhost:58359/getInfo.html) 03:36:14 INFO - PROCESS | 3575 | __metrics Screen width/height:1600/1200 03:36:14 INFO - PROCESS | 3575 | colorDepth:24 03:36:14 INFO - PROCESS | 3575 | Browser inner width/height: 1024/697 03:36:14 INFO - PROCESS | 3575 | __metrics 03:36:14 INFO - PROCESS | 3575 | 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" 03:36:14 INFO - PROCESS | 3575 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:36:15 INFO - PROCESS | 3575 | console.error: PushService: 03:36:15 INFO - PROCESS | 3575 | stateChangeProcessEnqueue: Error transitioning state 03:36:15 INFO - PROCESS | 3575 | UnknownError 03:36:16 INFO - TEST-INFO | 3575: exit 0 03:36:16 INFO - Browser initialized. 03:36:16 INFO - Running cycle 1/1 for kraken test... 03:36:16 INFO - TEST-INFO | started process 3657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfOSYUI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:28 INFO - PROCESS | 3657 | RSS: Main: 174706688 03:36:28 INFO - PROCESS | 3657 | 03:36:31 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58359/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:32 INFO - PROCESS | 3657 | RSS: Main: 233963520 03:36:32 INFO - PROCESS | 3657 | 03:36:35 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58359/tests/kraken/driver.html?testName=audio-dft) 03:36:35 INFO - PROCESS | 3657 | RSS: Main: 217690112 03:36:35 INFO - PROCESS | 3657 | 03:36:38 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58359/tests/kraken/driver.html?testName=audio-fft) 03:36:39 INFO - PROCESS | 3657 | RSS: Main: 299905024 03:36:39 INFO - PROCESS | 3657 | 03:36:41 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58359/tests/kraken/driver.html?testName=audio-oscillator) 03:36:42 INFO - PROCESS | 3657 | RSS: Main: 209195008 03:36:42 INFO - PROCESS | 3657 | 03:36:44 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:45 INFO - PROCESS | 3657 | RSS: Main: 212852736 03:36:45 INFO - PROCESS | 3657 | 03:36:48 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58359/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:48 INFO - PROCESS | 3657 | RSS: Main: 249942016 03:36:48 INFO - PROCESS | 3657 | 03:36:52 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58359/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:52 INFO - PROCESS | 3657 | RSS: Main: 249167872 03:36:52 INFO - PROCESS | 3657 | 03:36:55 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58359/tests/kraken/driver.html?testName=json-parse-financial) 03:36:56 INFO - PROCESS | 3657 | RSS: Main: 249012224 03:36:56 INFO - PROCESS | 3657 | 03:36:58 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:58 INFO - PROCESS | 3657 | RSS: Main: 211263488 03:36:58 INFO - PROCESS | 3657 | 03:37:00 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:37:01 INFO - PROCESS | 3657 | RSS: Main: 219308032 03:37:01 INFO - PROCESS | 3657 | 03:37:03 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:37:04 INFO - PROCESS | 3657 | RSS: Main: 223490048 03:37:04 INFO - PROCESS | 3657 | 03:37:07 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:37:07 INFO - PROCESS | 3657 | RSS: Main: 224112640 03:37:07 INFO - PROCESS | 3657 | 03:37:10 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:37:11 INFO - PROCESS | 3657 | RSS: Main: 221511680 03:37:11 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | Cycle 1(1): loaded http://localhost:58359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58359/tests/kraken/driver.html?testName=ai-astar) 03:37:13 INFO - PROCESS | 3657 | RSS: Main: 272871424 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | __start_tp_report 03:37:13 INFO - PROCESS | 3657 | _x_x_mozilla_page_load 03:37:13 INFO - PROCESS | 3657 | _x_x_mozilla_page_load_details 03:37:13 INFO - PROCESS | 3657 | |i|pagename|runs| 03:37:13 INFO - PROCESS | 3657 | |0;ai-astar;112;114;115;113;114;116;114;115;115;115 03:37:13 INFO - PROCESS | 3657 | |1;audio-beat-detection;165;122;122;170;123;135;124;122;149;129 03:37:13 INFO - PROCESS | 3657 | |2;audio-dft;162;163;163;162;164;163;162;163;162;163 03:37:13 INFO - PROCESS | 3657 | |3;audio-fft;77;76;76;113;77;77;80;76;108;76 03:37:13 INFO - PROCESS | 3657 | |4;audio-oscillator;84;83;127;84;83;83;109;84;84;119 03:37:13 INFO - PROCESS | 3657 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;117;117;116 03:37:13 INFO - PROCESS | 3657 | |6;imaging-darkroom;146;147;146;146;146;147;146;147;146;145 03:37:13 INFO - PROCESS | 3657 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;96 03:37:13 INFO - PROCESS | 3657 | |8;json-parse-financial;66;64;65;64;65;65;66;66;67;65 03:37:13 INFO - PROCESS | 3657 | |9;json-stringify-tinderbox;66;66;66;65;66;65;67;66;66;67 03:37:13 INFO - PROCESS | 3657 | |10;stanford-crypto-aes;82;92;83;85;83;86;89;83;83;91 03:37:13 INFO - PROCESS | 3657 | |11;stanford-crypto-ccm;142;134;131;132;147;136;143;142;183;134 03:37:13 INFO - PROCESS | 3657 | |12;stanford-crypto-pbkdf2;173;174;175;172;173;187;175;177;173;174 03:37:13 INFO - PROCESS | 3657 | |13;stanford-crypto-sha256-iterative;71;70;71;70;69;69;70;70;71;68 03:37:13 INFO - PROCESS | 3657 | __end_tp_report 03:37:13 INFO - PROCESS | 3657 | __start_cc_report 03:37:13 INFO - PROCESS | 3657 | _x_x_mozilla_cycle_collect,1948 03:37:13 INFO - PROCESS | 3657 | __end_cc_report 03:37:13 INFO - PROCESS | 3657 | __startTimestamp1474281433317__endTimestamp 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | ------- Summary: start ------- 03:37:13 INFO - PROCESS | 3657 | Number of tests: 14 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.16 (1.0%) stddev-sans-first:0.88 03:37:13 INFO - PROCESS | 3657 | Values: 112.0 114.0 115.0 113.0 114.0 116.0 114.0 115.0 115.0 115.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:132.00 stddev:18.61 (14.1%) stddev-sans-first:16.54 03:37:13 INFO - PROCESS | 3657 | Values: 165.0 122.0 122.0 170.0 123.0 135.0 124.0 122.0 149.0 129.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 03:37:13 INFO - PROCESS | 3657 | Values: 162.0 163.0 163.0 162.0 164.0 163.0 162.0 163.0 162.0 163.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.50 stddev:14.28 (18.7%) stddev-sans-first:14.94 03:37:13 INFO - PROCESS | 3657 | Values: 77.0 76.0 76.0 113.0 77.0 77.0 80.0 76.0 108.0 76.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:83.50 stddev:17.33 (20.8%) stddev-sans-first:18.00 03:37:13 INFO - PROCESS | 3657 | Values: 84.0 83.0 127.0 84.0 83.0 83.0 109.0 84.0 84.0 119.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 03:37:13 INFO - PROCESS | 3657 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 03:37:13 INFO - PROCESS | 3657 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 145.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:37:13 INFO - PROCESS | 3657 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.50 stddev:0.95 (1.4%) stddev-sans-first:0.97 03:37:13 INFO - PROCESS | 3657 | Values: 66.0 64.0 65.0 64.0 65.0 65.0 66.0 66.0 67.0 65.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 03:37:13 INFO - PROCESS | 3657 | Values: 66.0 66.0 66.0 65.0 66.0 65.0 67.0 66.0 66.0 67.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.50 stddev:3.68 (4.3%) stddev-sans-first:3.66 03:37:13 INFO - PROCESS | 3657 | Values: 82.0 92.0 83.0 85.0 83.0 86.0 89.0 83.0 83.0 91.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:142.00 stddev:15.24 (10.7%) stddev-sans-first:16.16 03:37:13 INFO - PROCESS | 3657 | Values: 142.0 134.0 131.0 132.0 147.0 136.0 143.0 142.0 183.0 134.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:174.50 stddev:4.35 (2.5%) stddev-sans-first:4.53 03:37:13 INFO - PROCESS | 3657 | Values: 173.0 174.0 175.0 172.0 173.0 187.0 175.0 177.0 173.0 174.0 03:37:13 INFO - PROCESS | 3657 | 03:37:13 INFO - PROCESS | 3657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:0.99 (1.4%) stddev-sans-first:0.97 03:37:13 INFO - PROCESS | 3657 | Values: 71.0 70.0 71.0 70.0 69.0 69.0 70.0 70.0 71.0 68.0 03:37:13 INFO - PROCESS | 3657 | -------- Summary: end -------- 03:37:13 INFO - PROCESS | 3657 | 03:37:14 INFO - TEST-INFO | 3657: exit 0 03:37:14 INFO - TEST-OK | kraken | took 65193ms 03:37:14 INFO - SUITE-END | took 1187s 03:37:14 INFO - Completed test suite (00:19:47) 03:37:14 INFO - javascript benchmark 03:37:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2581.3933725574493, 2603.193010166847, 2637.868106594671, 2640.775534079603, 2652.005497938272, 4553.2942393928915, 4620.976895115528, 4764.976175119124, 4788.449328327239, 4796.952030479694, 4638.953610463895, 4672.836450724217, 4685.83599574015, 4714.622830173586, 4723.622110231173, 4263.3178691409385, 4282.807273672676, 4298.140371925611, 4300.849470268541, 4365.126974605076, 4319.48166220054, 4368.650507959355, 4399.538048504909, 4403.207422663919, 4452.287633978564, 4222.050038741289, 4268.55180206077, 4337.457817772778, 4392.626626736731, 4426.313921342197, 4306.181825453154, 4335.479742430928, 4358.7166834156, 4387.868363949055, 4449.955500444987, 4333.783310834446, 4342.1532801103795, 4342.174986752539, 4346.978460061883, 4396.692231543793, 4774.594159496447, 4780.1873681474235, 4806.951930480685, 4808.08646357191, 4858.5384388483035, 4849.466558678542, 4898.975505122487, 4904.6321525885705, 4928.7535623219055, 4965.180745177042, 4977.0, 4990.700557966534, 4994.750262486898, 4995.750212489397, 5046.84859454217, 4695.2252878275385, 4700.130475861976, 4703.2004559225015, 4748.762561871893, 4761.190597598416, 4995.275685025651, 5044.016416798762, 5084.9745751271, 5126.820561280338, 5135.589152867761, 4912.6561140719805, 4913.091078150482, 4917.1149193144865, 4968.751562421937, 4978.975105124451, 5032.547070763649, 5052.090623687772, 5052.216906379517, 5059.241113832954, 5121.615878809104, 4814.662973591885, 4967.652264341461, 4975.875603109951, 5011.0, 5026.4973502649445, 4772.809087636525, 4870.732109733999, 4890.975545122252, 4891.192953162688, 4894.094592500399, 4155.688323375698, 4161.3549899765585, 4177.791110444466, 4222.0500387412585, 4236.428082208862, 3642.0348607619003, 3663.413853783382, 3683.447482877536, 3685.760425572329, 3718.2377612589353, 4293.162833247549, 4294.26997410441, 4294.527601963781, 4306.4832220133785, 4347.673924455678, 4842.903141937141, 4881.048195601824, 4998.850034498971, 5007.924881126786, 5017.974910125499, 4331.7834108294455, 4341.630961368319, 4350.1734670412525, 4370.628496577827, 4395.384646149541, 4538.319252112209, 4547.704399214041, 4558.997020655451, 4560.475545312265, 4590.816367345269, 4601.769911504344, 4651.790669419816, 4711.340412342343, 4746.050789841976, 4752.572268495852, 5067.163917953497, 5080.669756465818, 5084.1102807009365, 5128.564072053917, 5146.0, 4736.526347365374, 4742.620590352833, 4747.7863496143445, 4750.287456881495, 4811.735354555533, 4819.566239038503, 4853.563179313879, 4977.402711674622, 4984.647644724003, 5042.39491261051, 5077.847664570068, 5117.74411279442, 5121.359830021247, 5190.532852043334, 5192.74036298176, 4972.254161875748, 4976.2038073908, 4977.651564390603, 4978.4523702393435, 5040.495950405077, 4750.453697824656, 4786.712797232177, 4966.751662416792, 4972.254600157458, 4974.12952733263, 4752.0, 4780.737059461763, 4812.374391329245, 4828.179209534317, 4879.536444037811, 4872.074305881871, 4888.266759986031, 4904.068227036852, 4907.975460122677, 4966.801327946841, 4645.0, 4693.765311734332, 4695.154872123051, 4708.741019243975, 4813.278008298783], "value": 4607.693592450811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.5241197394189, 569.8432930943987, 581.0789898011651, 581.7294957844601, 583.5244275915128, 28097.29756756082, 39302.606973930255, 40395.19209615806, 40396.0, 43979.78010109953, 39824.40263396044, 40296.19407611846, 40310.19379612406, 40335.596644033554, 40357.59642403575, 7524.097108347006, 7657.693692252304, 7665.770026899174, 7674.270944260286, 7678.808029799272, 7470.626468676572, 7532.9246707532775, 7558.811029724246, 7559.69761209551, 7562.432817538706, 7546.7358642447525, 7547.509411888237, 7578.886316705254, 7590.810229744246, 7592.658330375147, 7549.547027178387, 7558.886616700726, 7592.430567707444, 7592.886106708432, 7593.164751877289, 7458.813529661747, 7521.548707077593, 7526.962365188139, 7527.5859827709455, 7534.698612055511, 39130.21739565221, 39352.212955740724, 39369.0, 39449.60550394488, 39495.21009579821, 2687.6640419947507, 2700.0954680182163, 2738.8493632850186, 2775.7918156138167, 2795.8042936994216, 28757.137285881458, 28847.374761921434, 28946.0, 29059.273518162216, 29289.56065659017, 3232.69289417505, 3266.657001014883, 3303.8678452861855, 3316.5522654441584, 3326.3513614845115, 243729.0, 245497.0, 245721.99999999822, 246401.0, 247417.0, 52134.21798673023, 52245.0, 52495.47504524982, 52516.21225681618, 52572.474275257526, 32359.822428411342, 32885.342293154004, 32913.83543082269, 35349.0, 35489.0, 13515.189088654515, 13668.248246346546, 13704.931475342759, 13706.246156461291, 13711.931440342934, 10109.443980581138, 10198.133158681596, 10218.744531386626, 10221.897781022244, 10225.130863876506, 13977.0, 14001.439942402394, 14053.367598458093, 14135.78796318056, 14161.221132837587, 6627.668616569153, 6653.168353955755, 6737.157855268091, 6911.550749201286, 6927.79216623492, 766.7738017284914, 854.8247609240091, 874.0254616102999, 900.2123142250531, 979.982073498664, 26725.465490690076, 27792.58311125335, 27967.300817479725, 28344.0, 28500.429991400055, 884.5444596033049, 912.3248795891126, 912.7854954085784, 937.2689302344182, 982.6118683120128, 14149.29253537319, 14890.925545372203, 14897.925510372379, 15013.69972600542, 15121.243937803067, 360487.0, 365005.0, 365158.0, 365206.0, 365584.0, 49578.0, 50078.74960625174, 50164.24753628699, 50209.0, 50242.49757502378, 1041.4896700616644, 1069.4332004037872, 1111.838783376422, 1116.609186784619, 1125.1280257800106, 27457.31356716098, 28458.0, 29182.708172918847, 29544.261393465335, 29553.704462955095, 42118.36822447604, 42667.14665706606, 42668.78665606652, 42723.35914961216, 42728.0, 299324.0, 299364.0, 301251.0, 301282.0, 301848.0, 22001.889990549946, 22444.418891826997, 23803.64294535618, 24552.508949820545, 24756.876215618806, 40696.593034070465, 40904.59095409127, 40998.79500602478, 41257.793711031256, 42026.0, 35349.1162720934, 35491.467627986116, 35537.82231088828, 35769.46345804867, 35957.28085438224, 34921.82539087288, 35023.0, 35204.47193292154, 35276.647233527336, 35395.46906796349, 34792.30415391627, 35156.296874062886, 35433.29133417266, 36053.81973090118, 36074.63925360818, 30016.699833001392, 31972.520412194302, 32045.67954320427, 32150.35699286048, 32188.678113218568, 24620.0, 24625.0, 24935.37661558403, 24966.251012469656, 25271.36821579475], "value": 16726.000027150247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.168531367375, 366.5473140671272, 380.82101412336203, 381.4278582126809, 396.7778044295195, 12118.939405302972, 12364.938175309122, 13642.727145457085, 16925.0, 17150.142492875337, 15373.38506459743, 15466.149361785097, 15552.0, 16145.031298122121, 16246.83753162468, 4829.082474329884, 4923.950760492394, 5063.518965698253, 5092.261622064799, 5095.388553373582, 3574.4817001534893, 5023.748812559375, 5110.105731496992, 5120.615953803461, 5120.948790512084, 3730.496382988289, 5499.257600223978, 5742.224799652056, 5755.165501002352, 5854.443827836349, 5697.77208911643, 5842.678652674102, 5854.502367298764, 5855.238818953509, 5947.1376650385455, 5266.8683282917855, 5268.236105764643, 5269.920951185735, 5270.6837589744355, 5273.419923808378, 17578.945263284244, 18308.54228644281, 18915.90542047281, 19008.809911900844, 19682.409527714193, 1647.6375197456541, 1764.1179410294853, 1849.140149830322, 1862.10618902926, 1888.8583356248337, 8931.598078086501, 9449.385789923634, 9498.382605130644, 11127.610533631285, 13228.933855330759, 1703.5059832648517, 1710.0509217384347, 1718.6219031812982, 1778.484239570523, 1794.0760508338217, 63849.680751595944, 70680.2931970684, 70821.2917870825, 71435.28564714287, 72158.0, 9161.404508706913, 9237.12247336502, 9362.672306469241, 9758.658446954465, 9927.950360248296, 12222.449989750483, 12225.0, 12235.449404776806, 12240.81638775419, 12244.265344079384, 6148.477379422709, 6333.366663333722, 6350.174172493196, 6701.229358623723, 6727.226368967631, 5945.227120474311, 6144.477719393813, 6336.398042185985, 6339.809805705836, 6365.809025729235, 4090.1615168890307, 4169.666426685858, 4179.226843034048, 4215.810288537024, 4370.169667763115, 1638.6476907464953, 1644.868410527155, 1726.956826079358, 1763.8588912886937, 1837.255911355903, 645.4320198225531, 811.4238890387891, 823.0658202939707, 827.2430725885868, 879.7360791762445, 15735.921320393554, 16044.43844465438, 16467.423640172776, 16684.499465015826, 17136.143192840307, 782.1787123520395, 804.284187073505, 817.2522142239784, 836.1011912194415, 893.4683863101444, 8537.914620853837, 9104.089591040844, 9117.726468205965, 9230.215431688383, 9258.72223833286, 379360.0, 391030.0, 391053.0, 391870.0, 392441.0, 80228.0, 80493.0, 82010.17989820025, 83042.0, 83423.5828820852, 909.508865212791, 917.7843206846386, 939.9201067909304, 949.7625593601599, 951.3911096897991, 14134.78797818013, 14263.572092837232, 14396.0, 14469.131852088909, 15045.473408430653, 33015.66984330126, 33524.49713254352, 34282.828585856914, 34401.31197376089, 34520.0, 45543.31685024794, 46907.53092469032, 47405.762971186305, 47509.287360688926, 47564.0, 6964.268751781193, 7040.628574270146, 7106.218315985341, 7207.927920720936, 7446.478746487694, 10714.9105924344, 11068.810276621074, 11146.442677865913, 11160.051395631463, 11262.83105753398, 6825.1127353443735, 9061.637534498546, 9414.435133891988, 9806.068423499755, 9879.160271377013, 8981.461112333282, 9225.769355766159, 9501.714948551555, 9687.370320929118, 9820.312578119749, 9722.805543889224, 9740.75648108803, 9755.512224388895, 10232.23258255619, 10609.469526523488, 6989.161300643752, 7010.894836577558, 7339.119305683352, 7349.1915889253205, 7368.300011498899, 8661.13388661129, 9539.713808585753, 9667.178289845442, 9964.701058968243, 9975.950120249352], "value": 7832.513060724144, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.8715722006407, 1394.26800929512, 1451.920144392058, 1628.9918550407247, 1667.7081510735618, 6837.4530037597, 7054.153501579817, 7339.926600733991, 7852.136265010851, 7855.410844186687, 7383.372413344859, 7643.732469363574, 7644.847103057936, 7872.488288261274, 7872.7638170854825, 2187.8687278763327, 2203.22886989554, 2217.268301460514, 2219.7891200335944, 2221.1559607349186, 1801.9099045047694, 1859.4142845003848, 2066.5866826634656, 2142.2287976328507, 2165.0473791531754, 2140.978590214101, 2195.5279614882797, 2205.3494219205295, 2206.161658569741, 2206.9668954965687, 2072.0882811562865, 2084.291340944079, 2089.707440958267, 2127.5000374911883, 2130.1479408236673, 2097.318371529259, 2107.7365329333834, 2114.0381126587367, 2116.43914362694, 2117.962198522728, 7654.19630938749, 7678.769636910902, 7741.148473667892, 7744.922550774477, 7760.30157285847, 4384.9561504384865, 4499.235130027871, 4518.593326600589, 4518.954810451886, 4519.435070616173, 2785.136607651614, 2913.621229240185, 2954.9113526594238, 2957.7338039576543, 2982.8806847726064, 948.7390967483929, 952.6284748947916, 968.4625033106623, 971.5773638467218, 980.9215262778961, 10591.94704026475, 10607.575696972111, 10639.0, 10639.946800265949, 10647.57409703611, 4080.1227736036894, 4175.603317684815, 4228.323468245066, 4307.0955099429475, 4322.848700295474, 8279.668813247523, 8395.832083358298, 8414.6213420395, 8421.368397370221, 8422.28410585095, 4447.666425018137, 4451.59935605797, 4452.9554704453185, 4476.664250181249, 4483.574060464251, 4087.8978025549004, 4102.815373308208, 4157.023099571598, 4157.91684166315, 4161.167766446722, 3211.277462570903, 3252.4145653782075, 3319.0, 3335.1328654549993, 3344.0803778960494, 952.6856137474755, 952.7856232347805, 957.4398976598768, 957.8419560772395, 981.1071924548769, 533.6691251424141, 688.8450098727747, 737.6791095873205, 755.4183278875236, 766.061574571157, 4514.232580461329, 4812.302216178635, 4822.831200908022, 5034.79860805571, 5114.3862736471865, 718.3104219948804, 738.1732459645232, 748.5433885329944, 773.2963003666637, 785.1441928298068, 3106.223444138965, 3158.289384888428, 3186.1715953851703, 3186.8087914725193, 3189.1389324882152, 9308.348415610977, 9805.80388392228, 9840.261980351503, 9841.212702983745, 9845.852312215322, 9676.371035882645, 9678.516074196263, 9698.224142068617, 9716.028397160228, 9716.854247186298, 7114.786556403316, 7257.0, 7270.8182295441975, 7275.199728029913, 7279.0, 4614.215583350771, 4645.651576131736, 4737.455192652889, 4747.216709242936, 4837.05677392905, 8448.788780280542, 8581.35639827003, 8796.956015219883, 8801.823963520821, 8826.91173088261, 8274.834503310021, 8428.072911979554, 8529.360297977553, 8552.401331906707, 8581.184787445183, 6277.403646653561, 6462.450691691259, 6472.288048314776, 6477.060826180272, 6481.319461456667, 7064.646767661699, 7107.573545587281, 7109.4667899908745, 7116.537425067354, 7134.1795693495915, 6414.134091897534, 6495.642739649365, 6534.640594767334, 6591.670416479061, 6607.80176594703, 6441.323661015714, 6456.418922297016, 6472.644004579794, 6485.416312531895, 6510.869782604226, 5843.328017278068, 5942.64344139353, 5984.491318237999, 5987.34139244674, 5993.910091348546, 4214.431051808089, 4225.598568136023, 4273.764942928169, 4294.334378171388, 4369.71596846204, 4215.683823713295, 4311.935320970125, 4332.956670433381, 4364.672649551233, 4403.5376285489565], "value": 3957.0406195629253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.5640686394432, 304.32743636563197, 307.42051233424996, 313.8807253243767, 314.8962885087767, 4795.640326975474, 4804.879878003043, 4806.759662016898, 4814.54261845125, 4857.368542089528, 4766.666333356667, 4776.259679749644, 4780.235162374021, 4784.760761961906, 4832.275158726193, 3592.1917568547065, 3674.6325367463305, 3685.94471082934, 3737.252549490113, 3764.4353346997896, 3765.1151979284846, 3766.1902690921447, 3767.660910518053, 3780.2250538639514, 3795.4496597993275, 3739.196072844338, 3749.9062523436996, 3749.9437508437395, 3754.155315054118, 3769.4345848122725, 3551.8579256829694, 3588.6052534221217, 3676.540432445931, 3732.3841566141555, 3750.081230098616, 3276.950845737316, 3292.341531693647, 3297.3405318936307, 3323.833808309599, 3335.6497567755277, 4572.977135114336, 4574.817007319703, 4576.711644177911, 4590.839320623795, 4593.908121837578, 4176.540580536139, 4219.767631330771, 4272.1028583997395, 4272.9359059614135, 4286.7856607169515, 4891.69810377997, 4900.681455705368, 4915.37027690184, 4925.7783399747095, 4943.802247910079, 3604.6122242936394, 3618.222082252328, 3622.601513833476, 3634.7819130852236, 3655.3785856404475, 4933.28467372229, 5133.974330128362, 5190.377154741455, 5197.766100525486, 5203.869903252411, 3644.963550364462, 3670.5778835433734, 3672.7979961102396, 3676.9632303677154, 3691.409374500121, 3722.1253005543676, 3744.700423966076, 3745.7939813309986, 3751.2872554214614, 3753.680937120321, 4212.915741685149, 4244.427002354642, 4253.915581365109, 4254.978805086756, 4280.957190428118, 4774.3077253798, 4881.536254055859, 4913.732367975328, 4958.256261560723, 4972.676776009548, 1302.6027061746258, 1310.4823594680047, 1310.678883673487, 1316.8946484281234, 1316.973660526784, 835.774340927946, 855.4354126276628, 865.1478293880523, 865.3769286113987, 874.7054359547905, 3975.0658595230097, 4224.5353011168745, 4281.49532523471, 4353.36876152956, 4439.445069366329, 5172.844814655567, 5173.163526947955, 5178.249153872667, 5181.585473162213, 5185.948140518546, 4433.780710304627, 4435.179491794029, 4445.355423463579, 4445.799939002753, 4449.888752781207, 2622.3313055171193, 2696.541587930017, 2713.552263876478, 2729.8362098274165, 2743.82165159264, 4846.660733748604, 4860.246661767432, 4862.520114741436, 4863.124637565202, 4866.562009419098, 5250.369955605352, 5261.921071183935, 5306.381553626104, 5306.92039619406, 5319.1223448131395, 4745.6440766942615, 4797.544233297831, 4842.297866809293, 4844.370231869834, 4874.926876096862, 5122.487751224848, 5129.230615407719, 5147.3308469898675, 5158.8968220636125, 5158.97420512895, 5020.548150666312, 5026.924596131134, 5027.547520723152, 5042.621803364689, 5044.4451110377095, 4614.653900957374, 4781.804548862784, 4826.710397376169, 4856.271559266138, 4861.295112208781, 3726.366517691945, 3734.234481931252, 3748.812559372075, 3750.324941510445, 3769.5099637048106, 5158.38099428071, 5173.7671804769625, 5201.479852014768, 5212.635115541876, 5213.869653258699, 4782.736949467673, 5024.221245706921, 5041.798328066836, 5043.092243396077, 5047.747612619428, 4990.8752281193265, 4991.0, 5015.548600625962, 5020.47285035066, 5020.673656212334, 4960.404751429707, 4973.751312434291, 4974.602031837517, 4978.32792572998, 4999.975000124976, 4800.855974320776, 4966.329545511309, 4969.701817890938, 4998.750062496788, 5035.471275516018, 4825.710457372569, 4831.202851529599, 4957.900841983213, 4983.676061056165, 4986.50134986513], "value": 3780.282164753835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.2440446767822, 551.589066145721, 552.8037546670935, 573.0802062689384, 581.6680739281359, 12358.691032724175, 13610.863891361083, 13614.659633509167, 13622.182669039865, 13625.0, 11647.126465515099, 12217.694557636041, 12473.189242699218, 12562.371881405916, 12575.119741618068, 741.7070257248397, 761.1437133225122, 762.8436170585045, 763.2748888555866, 764.2701220334567, 578.6788332475475, 579.7651950959847, 744.6314074533086, 752.2214507984237, 765.9885101723479, 767.6583920155533, 768.051456451285, 768.5734417398342, 770.2220757035378, 771.8842173673937, 759.8024513626442, 760.8744557148092, 763.904511936008, 767.8656235158825, 770.9845803083934, 458.3377020205804, 463.3166080031926, 473.2924278204072, 477.6537010667273, 562.2830890614458, 12017.519299228108, 12600.4329805159, 12614.432350544248, 12618.369081545887, 12620.55828046014, 10312.535935882903, 10653.360798352047, 10764.461776911201, 11186.720331991684, 11225.831612525819, 9090.409123407024, 9768.755781105458, 10454.372737635766, 10492.21308401873, 10515.421651809147, 3877.7673339599714, 3913.1391093959282, 3928.508936382952, 4000.559938406802, 4083.4283200351974, 58113.709431453, 58813.0, 58882.11676824851, 59208.70395647994, 59582.70208648972, 8833.823323533494, 8838.602262898185, 8904.174644197923, 8991.460512369278, 9085.0, 16075.276612552463, 16188.0, 16448.095354755602, 16513.256903439375, 16741.581460463585, 6098.69506524672, 6218.0, 6307.274663413675, 6455.289918109004, 6540.8364790880605, 3346.899593012165, 3355.932881342408, 3379.7465190110347, 3384.661533846596, 3486.651334866493, 2483.056438553338, 2550.7194208637034, 2558.5650439425344, 2562.0392352867675, 2565.486902619446, 457.425930457274, 457.8855286178455, 458.17985805408614, 477.6895018238173, 483.845169545749, 406.8392984770999, 412.2888018168695, 415.5346012465997, 418.0593664255425, 425.8360531195512, 6155.261368635793, 6381.074744162163, 6451.2903580607035, 6494.870102597922, 6928.237893831674, 317.78867053409226, 336.737344871001, 359.68886912820744, 376.94534292527976, 386.7370188272002, 3113.641931177898, 3323.80057196569, 3347.3807345641135, 3361.3445378151514, 3388.542546756206, 99059.50470247603, 101503.0, 104189.47905260425, 104459.477702611, 106062.0, 89754.0, 92618.0, 94401.0, 94483.0, 96216.0, 79267.60366198247, 81174.59412702899, 81349.59325203454, 81449.0, 81530.59234703789, 6163.845903852261, 6166.105914642262, 6207.96896015517, 6361.522885783492, 6457.192850893638, 46925.2961205589, 47194.40430776203, 47337.763311183226, 47792.7610361946, 49061.2640810381, 38139.80930095332, 41663.0, 42915.0, 43045.784771075945, 52768.736156320505, 8613.889597048763, 8691.95654021726, 8821.558922053744, 8997.910020899708, 9167.891943080826, 9875.506224688881, 9952.900470995197, 9960.356541170915, 9986.101250887456, 10014.64948726791, 9691.9030809691, 9742.415455072718, 10017.599296028076, 10195.643152489627, 10495.842562361724, 8882.333824963283, 9525.285603579621, 9629.229661627192, 9629.759256018655, 9661.806763864824, 9163.404378715362, 9330.626774929198, 9346.299027573095, 9657.855132172883, 9670.903290967, 7848.607569621382, 7925.365970722213, 8263.628136733982, 8600.13998600135, 8633.0, 7800.570968596781, 7890.171977994701, 7950.3639708822, 8031.839363212586, 8064.435489515678], "value": 5783.345788226065, "unit": "score"}], "name": "dromaeo_css", "value": 6113.876274508699, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 113.0, 114.0, 116.0, 114.0, 115.0, 115.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 122.0, 122.0, 170.0, 123.0, 135.0, 124.0, 122.0, 149.0, 129.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 164.0, 163.0, 162.0, 163.0, 162.0, 163.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 113.0, 77.0, 77.0, 80.0, 76.0, 108.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 127.0, 84.0, 83.0, 83.0, 109.0, 84.0, 84.0, 119.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0], "value": 146.2, "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, 96.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 66.0, 67.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 67.0, 66.0, 66.0, 67.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 83.0, 85.0, 83.0, 86.0, 89.0, 83.0, 83.0, 91.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 134.0, 131.0, 132.0, 147.0, 136.0, 143.0, 142.0, 183.0, 134.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 175.0, 172.0, 173.0, 187.0, 175.0, 177.0, 173.0, 174.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 70.0, 69.0, 69.0, 70.0, 70.0, 71.0, 68.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1553.7, "alertThreshold": 2.0}]} 03:37:15 INFO - Return code: 0 03:37:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:37:15 INFO - # TBPL SUCCESS # 03:37:15 INFO - Running post-action listener: _resource_record_post_action 03:37:15 INFO - [mozharness: 2016-09-19 10:37:15.113048Z] Finished run-tests step (success) 03:37:15 INFO - Running post-run listener: _resource_record_post_run 03:37:15 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 405123072; Read time: 260; Write time: 363060 03:37:15 INFO - TinderboxPrint: CPU usage
13.1% 03:37:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 260 03:37:15 INFO - TinderboxPrint: I/O write bytes / time
405,123,072 / 363,060 03:37:15 INFO - TinderboxPrint: CPU idle
8,349.8 (86.9%) 03:37:15 INFO - TinderboxPrint: CPU user
1,201.8 (12.5%) 03:37:15 INFO - TinderboxPrint: Swap in / out
0 / 0 03:37:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99774464; Read time: 0; Write time: 115932 03:37:15 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293978112; Read time: 244; Write time: 243764 03:37:15 INFO - Running post-run listener: _upload_blobber_files 03:37:15 INFO - Blob upload gear active. 03:37:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:37:15 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 03:37:15 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:37:15 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:37:16 INFO - (blobuploader) - INFO - Open directory for files ... 03:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:19 INFO - (blobuploader) - INFO - Done attempting. 03:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:20 INFO - (blobuploader) - INFO - Done attempting. 03:37:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:37:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:37:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:21 INFO - (blobuploader) - INFO - Done attempting. 03:37:21 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:21 INFO - Return code: 0 03:37:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0163cf265afa30fece7d1e1d86f0f59cafc1a962a89aae2a21f931a38aeca7710cf14b7c155bea2448a169b6a650b81ae508ffb20ed58161618b01d9fa7b39c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4c939769f6a193cb77c26316a81b37cc21c894b91b10aeb1ad1ebe9c76bd8131f390b09db1522c36425258a581fc850094fec9773425d0a8cad3298df22f43e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be65b6b4c1cedf18b5090b3862aa0e8221f0ff227bb000b0f71c66674221bb2941e0e6b171149d75085ac189350ffff5febe8ff559e8778c18a0b56d7f502284"} 03:37:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:21 INFO - Contents: 03:37:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0163cf265afa30fece7d1e1d86f0f59cafc1a962a89aae2a21f931a38aeca7710cf14b7c155bea2448a169b6a650b81ae508ffb20ed58161618b01d9fa7b39c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4c939769f6a193cb77c26316a81b37cc21c894b91b10aeb1ad1ebe9c76bd8131f390b09db1522c36425258a581fc850094fec9773425d0a8cad3298df22f43e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be65b6b4c1cedf18b5090b3862aa0e8221f0ff227bb000b0f71c66674221bb2941e0e6b171149d75085ac189350ffff5febe8ff559e8778c18a0b56d7f502284"} 03:37:21 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:21 INFO - Copying logs to upload dir... 03:37:21 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.228589 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-19 03:37:20.825843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 03:37:20.829386) ========= 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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0163cf265afa30fece7d1e1d86f0f59cafc1a962a89aae2a21f931a38aeca7710cf14b7c155bea2448a169b6a650b81ae508ffb20ed58161618b01d9fa7b39c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4c939769f6a193cb77c26316a81b37cc21c894b91b10aeb1ad1ebe9c76bd8131f390b09db1522c36425258a581fc850094fec9773425d0a8cad3298df22f43e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be65b6b4c1cedf18b5090b3862aa0e8221f0ff227bb000b0f71c66674221bb2941e0e6b171149d75085ac189350ffff5febe8ff559e8778c18a0b56d7f502284"} build_url:https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011273 build_url: 'https://queue.taskcluster.net/v1/task/d-He2fQIQiisOlU0-unW5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0163cf265afa30fece7d1e1d86f0f59cafc1a962a89aae2a21f931a38aeca7710cf14b7c155bea2448a169b6a650b81ae508ffb20ed58161618b01d9fa7b39c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4c939769f6a193cb77c26316a81b37cc21c894b91b10aeb1ad1ebe9c76bd8131f390b09db1522c36425258a581fc850094fec9773425d0a8cad3298df22f43e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/be65b6b4c1cedf18b5090b3862aa0e8221f0ff227bb000b0f71c66674221bb2941e0e6b171149d75085ac189350ffff5febe8ff559e8778c18a0b56d7f502284"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 03:37:20.859213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:37:20.859944) ========= 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=5bd038846534b2fdfa68eeae000001e2-1474280125.888767-1099487642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004398 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:37:20.942196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 03:37:20.942791) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 03:37:20.943499) ========= ========= Total master_lag: 0.19 =========