builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1474129653.03 results: success (0) buildid: 20160917010517 builduid: 05b90feeea334f6a9acad1be365ec998 revision: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.026518) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.027071) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.027394) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.042735) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.043150) ========= 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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 09:27:33-- 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% 43.5M=0s 2016-09-17 09:27:33 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100149 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.157769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.158123) ========= 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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014493 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.208726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:33.209285) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 --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 f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 --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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 09:27:33,553 truncating revision to first 12 chars 2016-09-17 09:27:33,553 Setting DEBUG logging. 2016-09-17 09:27:33,553 attempt 1/10 2016-09-17 09:27:33,553 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f4be1a7f9b3e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 09:27:34,127 unpacking tar archive at: autoland-f4be1a7f9b3e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.895623 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:34.120511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:34.121264) ========= script_repo_revision: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:34.122259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:34.123000) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:34.134255) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-17 09:27:34.134569) ========= /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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:27:34 INFO - MultiFileLogger online at 20160917 09:27:34 in /builds/slave/test 09:27:34 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 09:27:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:27:34 INFO - {'append_to_log': False, 09:27:34 INFO - 'base_work_dir': '/builds/slave/test', 09:27:34 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:27:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:27:34 INFO - 'branch': 'Autoland-Non-PGO', 09:27:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:27:34 INFO - 'config_files': ('talos/linux_config.py',), 09:27:34 INFO - 'default_actions': ('clobber', 09:27:34 INFO - 'read-buildbot-config', 09:27:34 INFO - 'download-and-extract', 09:27:34 INFO - 'populate-webroot', 09:27:34 INFO - 'create-virtualenv', 09:27:34 INFO - 'install', 09:27:34 INFO - 'run-tests'), 09:27:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:27:34 INFO - 'download_minidump_stackwalk': True, 09:27:34 INFO - 'download_symbols': 'ondemand', 09:27:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:27:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:27:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:27:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:27:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:27:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:27:34 INFO - 'installer_path': 'installer.exe', 09:27:34 INFO - 'log_level': 'info', 09:27:34 INFO - 'log_name': 'talos', 09:27:34 INFO - 'log_to_console': True, 09:27:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:27:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:27:34 INFO - 'opt_config_files': (), 09:27:34 INFO - 'pip_index': False, 09:27:34 INFO - 'sps_profile': False, 09:27:34 INFO - 'sps_profile_interval': 0, 09:27:34 INFO - 'suite': 'dromaeojs', 09:27:34 INFO - 'system_bits': '32', 09:27:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:27:34 INFO - 'title': 'talos-linux64-ix-031', 09:27:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:27:34 INFO - 'use_talos_json': True, 09:27:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:27:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:27:34 INFO - 'work_dir': 'build'} 09:27:34 INFO - [mozharness: 2016-09-17 16:27:34.570731Z] Running clobber step. 09:27:34 INFO - Running pre-action listener: _resource_record_pre_action 09:27:34 INFO - Running main action method: clobber 09:27:34 INFO - rmtree: /builds/slave/test/build 09:27:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:27:35 INFO - Running post-action listener: _resource_record_post_action 09:27:35 INFO - [mozharness: 2016-09-17 16:27:35.038077Z] Finished clobber step (success) 09:27:35 INFO - [mozharness: 2016-09-17 16:27:35.038176Z] Running read-buildbot-config step. 09:27:35 INFO - Running pre-action listener: _resource_record_pre_action 09:27:35 INFO - Running main action method: read_buildbot_config 09:27:35 INFO - Using buildbot properties: 09:27:35 INFO - { 09:27:35 INFO - "project": "", 09:27:35 INFO - "product": "firefox", 09:27:35 INFO - "script_repo_revision": "production", 09:27:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:27:35 INFO - "repository": "", 09:27:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:27:35 INFO - "buildid": "20160917010517", 09:27:35 INFO - "pgo_build": "False", 09:27:35 INFO - "basedir": "/builds/slave/test", 09:27:35 INFO - "buildnumber": 658, 09:27:35 INFO - "slavename": "talos-linux64-ix-031", 09:27:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:27:35 INFO - "platform": "ubuntu64_hw", 09:27:35 INFO - "scheduler": "tests-autoland-linux64-talos", 09:27:35 INFO - "branch": "autoland", 09:27:35 INFO - "repo_path": "integration/autoland", 09:27:35 INFO - "revision": "f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2", 09:27:35 INFO - "stage_platform": "linux64", 09:27:35 INFO - "builduid": "05b90feeea334f6a9acad1be365ec998", 09:27:35 INFO - "slavebuilddir": "test" 09:27:35 INFO - } 09:27:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:27:35 INFO - Running post-action listener: _resource_record_post_action 09:27:35 INFO - [mozharness: 2016-09-17 16:27:35.040618Z] Finished read-buildbot-config step (success) 09:27:35 INFO - [mozharness: 2016-09-17 16:27:35.040708Z] Running download-and-extract step. 09:27:35 INFO - Running pre-action listener: _resource_record_pre_action 09:27:35 INFO - Running main action method: download_and_extract 09:27:35 INFO - mkdir: /builds/slave/test/build/tests 09:27:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:35 INFO - trying https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:27:35 INFO - Downloading https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/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 09:27:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/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 09:27:35 INFO - Downloaded 1361 bytes. 09:27:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:27:35 INFO - Using the following test package requirements: 09:27:35 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:27:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:27:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:27:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'jsshell-linux-x86_64.zip'], 09:27:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:27:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:27:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:27:35 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:27:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:27:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:27:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:27:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:27:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:27:35 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:27:36 INFO - Expected file size: 20365601 09:27:40 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 09:27:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:27:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:27:40 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:27:40 INFO - Expected file size: 20365601 09:27:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:27:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:27:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:27:46 INFO - Expected file size: 14414048 09:27: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')]} 09:27:46 INFO - trying https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:46 INFO - Downloading https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/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 09:27:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/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 09:27:47 INFO - Downloaded 55598646 bytes. 09:27:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:47 INFO - mkdir: /builds/slave/test/properties 09:27:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:47 INFO - Contents: 09:27:47 INFO - build_url:https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:48 INFO - Running post-action listener: _resource_record_post_action 09:27:48 INFO - Running post-action listener: set_extra_try_arguments 09:27:48 INFO - [mozharness: 2016-09-17 16:27:48.402639Z] Finished download-and-extract step (success) 09:27:48 INFO - [mozharness: 2016-09-17 16:27:48.402867Z] Running populate-webroot step. 09:27:48 INFO - Running pre-action listener: _resource_record_pre_action 09:27:48 INFO - Running main action method: populate_webroot 09:27:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:48 INFO - '--tppagecycles', 09:27:48 INFO - '7'], 09:27:48 INFO - 'tests': ['tsvgm']}}, 09:27:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['damp', 'tps']}, 09:27:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'tests': ['damp', 'tps']}, 09:27:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['dromaeo_dom']}, 09:27:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['basic_compositor_video']}, 09:27:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['a11yr', 09:27:48 INFO - 'ts_paint', 09:27:48 INFO - 'tpaint', 09:27:48 INFO - 'sessionrestore', 09:27:48 INFO - 'sessionrestore_no_auto_restore', 09:27:48 INFO - 'tabpaint']}, 09:27:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['tsvgx', 09:27:48 INFO - 'tsvgr_opacity', 09:27:48 INFO - 'tart', 09:27:48 INFO - 'tscrollx', 09:27:48 INFO - 'cart']}, 09:27:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:48 INFO - 'tsvgr_opacity', 09:27:48 INFO - 'tart', 09:27:48 INFO - 'tscrollx', 09:27:48 INFO - 'cart']}, 09:27:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'talos_options': ['--disable-e10s'], 09:27:48 INFO - 'tests': ['tp5o']}, 09:27:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'tests': ['tp5o']}, 09:27:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'talos_options': ['--disable-e10s', 09:27:48 INFO - '--xperf_path', 09:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:48 INFO - 'tests': ['tp5n']}, 09:27:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:48 INFO - 'talos_options': ['--xperf_path', 09:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:48 INFO - 'tests': ['tp5n']}}, 09:27:48 INFO - 'talos.zip': {'path': '', 09:27:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:48 INFO - Running post-action listener: _resource_record_post_action 09:27:48 INFO - [mozharness: 2016-09-17 16:27:48.425552Z] Finished populate-webroot step (success) 09:27:48 INFO - [mozharness: 2016-09-17 16:27:48.425641Z] Running create-virtualenv step. 09:27:48 INFO - Running pre-action listener: _resource_record_pre_action 09:27:48 INFO - Running main action method: create_virtualenv 09:27:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:48 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 09:27:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:48 INFO - Using real prefix '/usr' 09:27:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:51 INFO - Installing pip.................done. 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27: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 09:27: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 09:27:51 INFO - Using env: {'DISPLAY': ':0', 09:27:51 INFO - 'HOME': '/home/cltbld', 09:27:51 INFO - 'LANG': 'en_US.UTF-8', 09:27:51 INFO - 'LANGUAGE': 'en_US:en', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MAIL': '/var/mail/cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SHLVL': '1', 09:27:51 INFO - 'TERM': 'linux', 09:27:51 INFO - 'TMOUT': '86400', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - '_': '/tools/buildbot/bin/python'} 09:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27: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 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27: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 09:27: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 09:27:51 INFO - Using env: {'DISPLAY': ':0', 09:27:51 INFO - 'HOME': '/home/cltbld', 09:27:51 INFO - 'LANG': 'en_US.UTF-8', 09:27:51 INFO - 'LANGUAGE': 'en_US:en', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MAIL': '/var/mail/cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SHLVL': '1', 09:27:51 INFO - 'TERM': 'linux', 09:27:51 INFO - 'TMOUT': '86400', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - '_': '/tools/buildbot/bin/python'} 09:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:51 INFO - Downloading/unpacking psutil>=3.1.1 09:27:51 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 09:27:51 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 09:27:51 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 09:27:51 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 09:27:51 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 09:27:51 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 09:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:53 INFO - Installing collected packages: psutil 09:27:53 INFO - Running setup.py install for psutil 09:27:53 INFO - building 'psutil._psutil_linux' extension 09:27:53 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 09:27:53 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 09:27:53 INFO - building 'psutil._psutil_posix' extension 09:27:53 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 09:27:53 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 09:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:53 INFO - Successfully installed psutil 09:27:53 INFO - Cleaning up... 09:27:54 INFO - Return code: 0 09:27:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27: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 09:27: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 09:27:54 INFO - Using env: {'DISPLAY': ':0', 09:27:54 INFO - 'HOME': '/home/cltbld', 09:27:54 INFO - 'LANG': 'en_US.UTF-8', 09:27:54 INFO - 'LANGUAGE': 'en_US:en', 09:27:54 INFO - 'LOGNAME': 'cltbld', 09:27:54 INFO - 'MAIL': '/var/mail/cltbld', 09:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:54 INFO - 'MOZ_NO_REMOTE': '1', 09:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:54 INFO - 'NO_EM_RESTART': '1', 09:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:54 INFO - 'PWD': '/builds/slave/test', 09:27:54 INFO - 'SHELL': '/bin/bash', 09:27:54 INFO - 'SHLVL': '1', 09:27:54 INFO - 'TERM': 'linux', 09:27:54 INFO - 'TMOUT': '86400', 09:27:54 INFO - 'USER': 'cltbld', 09:27:54 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:54 INFO - '_': '/tools/buildbot/bin/python'} 09:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:56 INFO - Installing collected packages: mozsystemmonitor 09:27:56 INFO - Running setup.py install for mozsystemmonitor 09:27:56 INFO - Successfully installed mozsystemmonitor 09:27:56 INFO - Cleaning up... 09:27:56 INFO - Return code: 0 09:27:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27: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 09:27: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 09:27:56 INFO - Using env: {'DISPLAY': ':0', 09:27:56 INFO - 'HOME': '/home/cltbld', 09:27:56 INFO - 'LANG': 'en_US.UTF-8', 09:27:56 INFO - 'LANGUAGE': 'en_US:en', 09:27:56 INFO - 'LOGNAME': 'cltbld', 09:27:56 INFO - 'MAIL': '/var/mail/cltbld', 09:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:56 INFO - 'MOZ_NO_REMOTE': '1', 09:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:56 INFO - 'NO_EM_RESTART': '1', 09:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:56 INFO - 'PWD': '/builds/slave/test', 09:27:56 INFO - 'SHELL': '/bin/bash', 09:27:56 INFO - 'SHLVL': '1', 09:27:56 INFO - 'TERM': 'linux', 09:27:56 INFO - 'TMOUT': '86400', 09:27:56 INFO - 'USER': 'cltbld', 09:27:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:56 INFO - '_': '/tools/buildbot/bin/python'} 09:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:56 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 09:27:56 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 09:27:56 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 09:27:56 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 09:27:56 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 09:27:56 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 09:27:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:58 INFO - warning: no files found matching '*.txt' 09:27:58 INFO - no previously-included directories found matching 'build' 09:27:58 INFO - no previously-included directories found matching 'dist' 09:27:58 INFO - no previously-included directories found matching '.git*' 09:27:58 INFO - Installing collected packages: jsonschema, functools32 09:27:58 INFO - Running setup.py install for functools32 09:27:58 INFO - warning: no files found matching '*.txt' 09:27:58 INFO - no previously-included directories found matching 'build' 09:27:58 INFO - no previously-included directories found matching 'dist' 09:27:58 INFO - no previously-included directories found matching '.git*' 09:27:58 INFO - Successfully installed jsonschema functools32 09:27:58 INFO - Cleaning up... 09:27:58 INFO - Return code: 0 09:27:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:58 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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27:58 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 09:27:58 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 09:27:58 INFO - Using env: {'DISPLAY': ':0', 09:27:58 INFO - 'HOME': '/home/cltbld', 09:27:58 INFO - 'LANG': 'en_US.UTF-8', 09:27:58 INFO - 'LANGUAGE': 'en_US:en', 09:27:58 INFO - 'LOGNAME': 'cltbld', 09:27:58 INFO - 'MAIL': '/var/mail/cltbld', 09:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:58 INFO - 'MOZ_NO_REMOTE': '1', 09:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:58 INFO - 'NO_EM_RESTART': '1', 09:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:58 INFO - 'PWD': '/builds/slave/test', 09:27:58 INFO - 'SHELL': '/bin/bash', 09:27:58 INFO - 'SHLVL': '1', 09:27:58 INFO - 'TERM': 'linux', 09:27:58 INFO - 'TMOUT': '86400', 09:27:58 INFO - 'USER': 'cltbld', 09:27:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:58 INFO - '_': '/tools/buildbot/bin/python'} 09:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:59 INFO - Cleaning up... 09:27:59 INFO - Return code: 0 09:27:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:27: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 09:27: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 09:27:59 INFO - Using env: {'DISPLAY': ':0', 09:27:59 INFO - 'HOME': '/home/cltbld', 09:27:59 INFO - 'LANG': 'en_US.UTF-8', 09:27:59 INFO - 'LANGUAGE': 'en_US:en', 09:27:59 INFO - 'LOGNAME': 'cltbld', 09:27:59 INFO - 'MAIL': '/var/mail/cltbld', 09:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:59 INFO - 'MOZ_NO_REMOTE': '1', 09:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:59 INFO - 'NO_EM_RESTART': '1', 09:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:59 INFO - 'PWD': '/builds/slave/test', 09:27:59 INFO - 'SHELL': '/bin/bash', 09:27:59 INFO - 'SHLVL': '1', 09:27:59 INFO - 'TERM': 'linux', 09:27:59 INFO - 'TMOUT': '86400', 09:27:59 INFO - 'USER': 'cltbld', 09:27:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:59 INFO - '_': '/tools/buildbot/bin/python'} 09:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:28:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:28:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:28:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 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 09:28:01 INFO - Downloading docopt-0.6.1.tar.gz 09:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:28:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:28:01 INFO - Running setup.py install for blobuploader 09:28:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:28:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:28:02 INFO - Running setup.py install for requests 09:28:02 INFO - Running setup.py install for docopt 09:28:02 INFO - Successfully installed blobuploader requests docopt 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:02 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')]} 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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')]} 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:28:02 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 09:28:02 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 09:28:02 INFO - Using env: {'DISPLAY': ':0', 09:28:02 INFO - 'HOME': '/home/cltbld', 09:28:02 INFO - 'LANG': 'en_US.UTF-8', 09:28:02 INFO - 'LANGUAGE': 'en_US:en', 09:28:02 INFO - 'LOGNAME': 'cltbld', 09:28:02 INFO - 'MAIL': '/var/mail/cltbld', 09:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:02 INFO - 'MOZ_NO_REMOTE': '1', 09:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:02 INFO - 'NO_EM_RESTART': '1', 09:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:02 INFO - 'PWD': '/builds/slave/test', 09:28:02 INFO - 'SHELL': '/bin/bash', 09:28:02 INFO - 'SHLVL': '1', 09:28:02 INFO - 'TERM': 'linux', 09:28:02 INFO - 'TMOUT': '86400', 09:28:02 INFO - 'USER': 'cltbld', 09:28:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:02 INFO - '_': '/tools/buildbot/bin/python'} 09:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:02 INFO - Running setup.py (path:/tmp/pip-heoYi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:02 INFO - Running setup.py (path:/tmp/pip-Ko_2gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:02 INFO - Running setup.py (path:/tmp/pip-VBDHqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:03 INFO - Running setup.py (path:/tmp/pip-i8yKVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:03 INFO - Running setup.py (path:/tmp/pip-si5Dpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:03 INFO - Running setup.py (path:/tmp/pip-MLZIoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:03 INFO - Running setup.py (path:/tmp/pip-LclhEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:03 INFO - Running setup.py (path:/tmp/pip-Alki2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:03 INFO - Running setup.py (path:/tmp/pip-46C3T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:03 INFO - Running setup.py (path:/tmp/pip-mGX_it-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:03 INFO - Running setup.py (path:/tmp/pip-gNjJjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:03 INFO - Running setup.py (path:/tmp/pip-Ojw4gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:03 INFO - Running setup.py (path:/tmp/pip-XJRTZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:03 INFO - Running setup.py (path:/tmp/pip-YmOYAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:03 INFO - Running setup.py (path:/tmp/pip-4TuJ60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:03 INFO - Running setup.py (path:/tmp/pip-dZ6cjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:03 INFO - Running setup.py (path:/tmp/pip-XFcQif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:28:04 INFO - Running setup.py install for manifestparser 09:28:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Running setup.py install for mozcrash 09:28:04 INFO - Running setup.py install for mozdebug 09:28:04 INFO - Running setup.py install for mozdevice 09:28:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Running setup.py install for mozfile 09:28:04 INFO - Running setup.py install for mozhttpd 09:28:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Running setup.py install for mozinfo 09:28:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Running setup.py install for mozInstall 09:28:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:28:04 INFO - Running setup.py install for mozleak 09:28:04 INFO - Running setup.py install for mozlog 09:28:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Running setup.py install for moznetwork 09:28:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Running setup.py install for mozprocess 09:28:05 INFO - Running setup.py install for mozprofile 09:28:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Running setup.py install for mozrunner 09:28:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Running setup.py install for mozscreenshot 09:28:05 INFO - Running setup.py install for moztest 09:28:05 INFO - Running setup.py install for mozversion 09:28:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:28:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:28:05 INFO - Cleaning up... 09:28:05 INFO - Return code: 0 09:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:05 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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:28:05 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 09:28:05 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 09:28:05 INFO - Using env: {'DISPLAY': ':0', 09:28:05 INFO - 'HOME': '/home/cltbld', 09:28:05 INFO - 'LANG': 'en_US.UTF-8', 09:28:05 INFO - 'LANGUAGE': 'en_US:en', 09:28:05 INFO - 'LOGNAME': 'cltbld', 09:28:05 INFO - 'MAIL': '/var/mail/cltbld', 09:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:05 INFO - 'MOZ_NO_REMOTE': '1', 09:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:05 INFO - 'NO_EM_RESTART': '1', 09:28:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:05 INFO - 'PWD': '/builds/slave/test', 09:28:05 INFO - 'SHELL': '/bin/bash', 09:28:05 INFO - 'SHLVL': '1', 09:28:05 INFO - 'TERM': 'linux', 09:28:05 INFO - 'TMOUT': '86400', 09:28:05 INFO - 'USER': 'cltbld', 09:28:05 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:05 INFO - '_': '/tools/buildbot/bin/python'} 09:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:06 INFO - Running setup.py (path:/tmp/pip-ACbStG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28: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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:06 INFO - Running setup.py (path:/tmp/pip-FJUUwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:06 INFO - Running setup.py (path:/tmp/pip-wgul6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:06 INFO - Running setup.py (path:/tmp/pip-IeJ907-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:06 INFO - Running setup.py (path:/tmp/pip-sWszFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:06 INFO - Running setup.py (path:/tmp/pip-VaPbKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:06 INFO - Running setup.py (path:/tmp/pip-5fSnCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:06 INFO - Running setup.py (path:/tmp/pip-26Vf2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:06 INFO - Running setup.py (path:/tmp/pip-KVszKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:06 INFO - Running setup.py (path:/tmp/pip-_MFHnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:06 INFO - Running setup.py (path:/tmp/pip-rnwg2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:06 INFO - Running setup.py (path:/tmp/pip-ULdNeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:06 INFO - Running setup.py (path:/tmp/pip-3JgXe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:06 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)) 09:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:07 INFO - Running setup.py (path:/tmp/pip-Ylg3_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28: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)) 09:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:07 INFO - Running setup.py (path:/tmp/pip-lBfgRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28: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)) 09:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:07 INFO - Running setup.py (path:/tmp/pip-s3ZL_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:07 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)) 09:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:07 INFO - Running setup.py (path:/tmp/pip-LBhxal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:07 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)) 09:28:07 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)) 09:28:07 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)) 09:28:07 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)) 09:28:07 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)) 09:28:07 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)) 09:28:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:09 INFO - Downloading blessings-1.6.tar.gz 09:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:28:09 INFO - Installing collected packages: blessings 09:28:09 INFO - Running setup.py install for blessings 09:28:09 INFO - Successfully installed blessings 09:28:09 INFO - Cleaning up... 09:28:09 INFO - Return code: 0 09:28:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:09 INFO - Reading from file tmpfile_stdout 09:28:09 INFO - Current package versions: 09:28:09 INFO - argparse == 1.2.1 09:28:09 INFO - blessings == 1.6 09:28:09 INFO - blobuploader == 1.2.4 09:28:09 INFO - docopt == 0.6.1 09:28:09 INFO - functools32 == 3.2.3-2 09:28:09 INFO - jsonschema == 2.5.1 09:28:09 INFO - manifestparser == 1.1 09:28:09 INFO - mozInstall == 1.12 09:28:09 INFO - mozcrash == 0.17 09:28:09 INFO - mozdebug == 0.1 09:28:09 INFO - mozdevice == 0.48 09:28:09 INFO - mozfile == 1.2 09:28:09 INFO - mozhttpd == 0.7 09:28:09 INFO - mozinfo == 0.9 09:28:09 INFO - mozleak == 0.1 09:28:09 INFO - mozlog == 3.4 09:28:09 INFO - moznetwork == 0.27 09:28:09 INFO - mozprocess == 0.23 09:28:09 INFO - mozprofile == 0.28 09:28:09 INFO - mozrunner == 6.12 09:28:09 INFO - mozscreenshot == 0.1 09:28:09 INFO - mozsystemmonitor == 0.3 09:28:09 INFO - moztest == 0.7 09:28:09 INFO - mozversion == 1.4 09:28:09 INFO - psutil == 3.1.1 09:28:09 INFO - requests == 1.2.3 09:28:09 INFO - wsgiref == 0.1.2 09:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:09 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')]} 09:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:09 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')]} 09:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:09 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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:28:09 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 09:28:09 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 09:28:09 INFO - Using env: {'DISPLAY': ':0', 09:28:09 INFO - 'HOME': '/home/cltbld', 09:28:09 INFO - 'LANG': 'en_US.UTF-8', 09:28:09 INFO - 'LANGUAGE': 'en_US:en', 09:28:09 INFO - 'LOGNAME': 'cltbld', 09:28:09 INFO - 'MAIL': '/var/mail/cltbld', 09:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:09 INFO - 'MOZ_NO_REMOTE': '1', 09:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:09 INFO - 'NO_EM_RESTART': '1', 09:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:09 INFO - 'PWD': '/builds/slave/test', 09:28:09 INFO - 'SHELL': '/bin/bash', 09:28:09 INFO - 'SHLVL': '1', 09:28:09 INFO - 'TERM': 'linux', 09:28:09 INFO - 'TMOUT': '86400', 09:28:09 INFO - 'USER': 'cltbld', 09:28:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:09 INFO - '_': '/tools/buildbot/bin/python'} 09:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 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)) 09:28:09 INFO - Cleaning up... 09:28:09 INFO - Return code: 0 09:28:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:28:09 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')]} 09:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:09 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')]} 09:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:09 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 0x7f7df266bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, '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': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', '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 09:28:09 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 09:28:09 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 09:28:09 INFO - Using env: {'DISPLAY': ':0', 09:28:09 INFO - 'HOME': '/home/cltbld', 09:28:09 INFO - 'LANG': 'en_US.UTF-8', 09:28:09 INFO - 'LANGUAGE': 'en_US:en', 09:28:09 INFO - 'LOGNAME': 'cltbld', 09:28:09 INFO - 'MAIL': '/var/mail/cltbld', 09:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:09 INFO - 'MOZ_NO_REMOTE': '1', 09:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:09 INFO - 'NO_EM_RESTART': '1', 09:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:09 INFO - 'PWD': '/builds/slave/test', 09:28:09 INFO - 'SHELL': '/bin/bash', 09:28:09 INFO - 'SHLVL': '1', 09:28:09 INFO - 'TERM': 'linux', 09:28:09 INFO - 'TMOUT': '86400', 09:28:09 INFO - 'USER': 'cltbld', 09:28:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:09 INFO - '_': '/tools/buildbot/bin/python'} 09:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:28:10 INFO - Cleaning up... 09:28:10 INFO - Return code: 0 09:28:10 INFO - Running post-action listener: _resource_record_post_action 09:28:10 INFO - Running post-action listener: _start_resource_monitoring 09:28:10 INFO - Starting resource monitoring. 09:28:10 INFO - [mozharness: 2016-09-17 16:28:10.178309Z] Finished create-virtualenv step (success) 09:28:10 INFO - [mozharness: 2016-09-17 16:28:10.178761Z] Running install step. 09:28:10 INFO - Running pre-action listener: _resource_record_pre_action 09:28:10 INFO - Running main action method: install 09:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:10 INFO - Reading from file tmpfile_stdout 09:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 09:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:28:10 INFO - Reading from file tmpfile_stdout 09:28:10 INFO - Output received: 09:28:10 INFO - Usage: mozinstall [options] installer 09:28:10 INFO - Options: 09:28:10 INFO - -h, --help show this help message and exit 09:28:10 INFO - -d DEST, --destination=DEST 09:28:10 INFO - Directory to install application into. [default: 09:28:10 INFO - "/builds/slave/test"] 09:28:10 INFO - --app=APP Application being installed. [default: firefox] 09:28:10 INFO - mkdir: /builds/slave/test/build/application 09:28:10 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'] 09:28:10 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 09:28:26 INFO - Reading from file tmpfile_stdout 09:28:26 INFO - Output received: 09:28:26 INFO - /builds/slave/test/build/application/firefox/firefox 09:28:26 INFO - Running post-action listener: _resource_record_post_action 09:28:26 INFO - [mozharness: 2016-09-17 16:28:26.509356Z] Finished install step (success) 09:28:26 INFO - [mozharness: 2016-09-17 16:28:26.509668Z] Running run-tests step. 09:28:26 INFO - Running pre-action listener: _resource_record_pre_action 09:28:26 INFO - Running main action method: run_tests 09:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:26 INFO - Python 2.7.3 09:28:26 INFO - Return code: 0 09:28:26 INFO - grabbing minidump binary from tooltool 09:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:26 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 0x1c39f60>, '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 0x1c3ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3b1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:26 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 09:28:26 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 09:28:26 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 09:28:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:26 INFO - Return code: 0 09:28:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:28:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:28:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:26 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/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 09:28:26 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/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 09:28:26 INFO - Using env: {'DISPLAY': ':0', 09:28:26 INFO - 'HOME': '/home/cltbld', 09:28:26 INFO - 'LANG': 'en_US.UTF-8', 09:28:26 INFO - 'LANGUAGE': 'en_US:en', 09:28:26 INFO - 'LOGNAME': 'cltbld', 09:28:26 INFO - 'MAIL': '/var/mail/cltbld', 09:28:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:26 INFO - 'MOZ_NO_REMOTE': '1', 09:28:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:26 INFO - 'NO_EM_RESTART': '1', 09:28:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:26 INFO - 'PWD': '/builds/slave/test', 09:28:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:26 INFO - 'SHELL': '/bin/bash', 09:28:26 INFO - 'SHLVL': '1', 09:28:26 INFO - 'TERM': 'linux', 09:28:26 INFO - 'TMOUT': '86400', 09:28:26 INFO - 'USER': 'cltbld', 09:28:26 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807', 09:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:26 INFO - '_': '/tools/buildbot/bin/python'} 09:28:26 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/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 09:28:26 INFO - mozversion application_buildid: 20160917010517 09:28:26 INFO - mozversion application_changeset: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 09:28:26 INFO - mozversion application_display_name: Nightly 09:28:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:26 INFO - mozversion application_name: Firefox 09:28:26 INFO - mozversion application_remotingname: firefox 09:28:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:28:26 INFO - mozversion application_vendor: Mozilla 09:28:26 INFO - mozversion application_version: 51.0a1 09:28:26 INFO - mozversion platform_buildid: 20160917010517 09:28:26 INFO - mozversion platform_changeset: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 09:28:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:28:26 INFO - mozversion platform_version: 51.0a1 09:28:26 INFO - using testdate: 1474129706 09:28:26 INFO - actual date: 1474129706 09:28:26 INFO - starting webserver on 'localhost:45383' 09:28:26 INFO - SUITE-START | Running 2 tests 09:28:26 INFO - TEST-START | dromaeo_css 09:28:26 INFO - operating with platform_type : linux_ 09:28:26 INFO - Initialising browser for dromaeo_css test... 09:28:26 INFO - TEST-INFO | started process 1726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3BSI_q/profile http://localhost:45383/getInfo.html) 09:28:31 INFO - PROCESS | 1726 | __metrics Screen width/height:1600/1200 09:28:31 INFO - PROCESS | 1726 | colorDepth:24 09:28:31 INFO - PROCESS | 1726 | Browser inner width/height: 1024/697 09:28:31 INFO - PROCESS | 1726 | __metrics 09:28:31 INFO - PROCESS | 1726 | 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" 09:28:31 INFO - PROCESS | 1726 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:32 INFO - PROCESS | 1726 | console.error: PushService: 09:28:32 INFO - PROCESS | 1726 | stateChangeProcessEnqueue: Error transitioning state 09:28:32 INFO - PROCESS | 1726 | UnknownError 09:28:33 INFO - TEST-INFO | 1726: exit 0 09:28:33 INFO - Browser initialized. 09:28:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:33 INFO - TEST-INFO | started process 1807 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3BSI_q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:45 INFO - PROCESS | 1807 | RSS: Main: 174178304 09:28:45 INFO - PROCESS | 1807 | 09:29:23 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:29:37 INFO - PROCESS | 1807 | 1474129777053 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] 09:29:43 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:29:44 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:30:00 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:30:02 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:30:10 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 09:30:18 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:30:20 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:30:22 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 09:30:25 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.73341 seconds. 09:30:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 09:30:32 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:30:37 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 09:30:45 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 09:30:56 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:30:58 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 09:31:01 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 09:31:05 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 09:31:17 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:31:19 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:31:43 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:31:49 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45383/tests/dromaeo/cssquery-ext.html) 09:31:50 INFO - PROCESS | 1807 | RSS: Main: 310206464 09:31:50 INFO - PROCESS | 1807 | 09:32:31 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:32:46 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:32:48 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 09:32:52 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:32:56 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:33:02 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:33:04 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:33:08 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:33:19 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:33:30 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:33:40 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:34:13 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:34:16 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:34:20 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:34:21 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:34:25 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:34:31 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:34:33 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:34:35 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:34:39 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:34:41 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:34:53 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-ext.html (next: http://localhost:45383/tests/dromaeo/cssquery-jquery.html) 09:34:54 INFO - PROCESS | 1807 | RSS: Main: 282107904 09:34:54 INFO - PROCESS | 1807 | 09:35:00 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 09:35:14 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:35:19 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:35:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:35:30 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:35:31 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:35:33 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:35:45 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:35:53 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:36:19 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:36:21 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:36:23 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:36:30 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:36:33 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 09:36:41 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:36:47 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:37:02 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:04 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:37:08 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:37:10 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:37:12 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:14 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:37:16 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:37:24 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:37:26 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:37:30 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:37:34 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:36 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:38 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:40 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:37:48 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:54 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:56 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45383/tests/dromaeo/cssquery-mootools.html) 09:37:57 INFO - PROCESS | 1807 | RSS: Main: 220270592 09:37:57 INFO - PROCESS | 1807 | 09:38:00 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:38:11 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:17 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:38:21 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:23 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:38:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:38:42 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00088 seconds. 09:38:51 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:39:08 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:39:14 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:39:16 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:24 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:47 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:39:48 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:54 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:39:58 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 09:40:26 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:40:29 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:40:31 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:40:33 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:41 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 09:40:45 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:40:47 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:40:59 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45383/tests/dromaeo/cssquery-prototype.html) 09:40:59 INFO - PROCESS | 1807 | RSS: Main: 222814208 09:40:59 INFO - PROCESS | 1807 | 09:41:04 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:41:08 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:41:18 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:41:26 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:41:51 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 09:42:03 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:42:08 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 09:42:21 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:42:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:42:36 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.06674 seconds. 09:42:38 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:42:40 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:42:44 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:42:46 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:42:47 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 2.00004 seconds. 09:42:56 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:42:58 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 09:43:03 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:43:04 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:43:15 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:43:18 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:43:20 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:43:22 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:43:24 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:43:32 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:43:35 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:43:42 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:43:44 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:43:47 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:43:49 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:43:57 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:43:59 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:44:01 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:44:03 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:03 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45383/tests/dromaeo/cssquery-yui.html) 09:44:03 INFO - PROCESS | 1807 | RSS: Main: 221130752 09:44:03 INFO - PROCESS | 1807 | 09:44:09 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 09:44:14 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:44:18 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:44:26 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:44:48 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:50 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:46:11 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:25 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 09:46:28 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 09:46:29 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:46:31 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:38 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:47:06 INFO - PROCESS | 1807 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 09:47:06 INFO - PROCESS | 1807 | Cycle 1(1): loaded http://localhost:45383/tests/dromaeo/cssquery-yui.html (next: http://localhost:45383/tests/dromaeo/cssquery-dojo.html) 09:47:06 INFO - PROCESS | 1807 | RSS: Main: 312025088 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | __start_tp_report 09:47:06 INFO - PROCESS | 1807 | _x_x_mozilla_page_load 09:47:06 INFO - PROCESS | 1807 | _x_x_mozilla_page_load_details 09:47:06 INFO - PROCESS | 1807 | |i|pagename|runs| 09:47:06 INFO - PROCESS | 1807 | |0;dojo.html;2567.666203393559;2588.2882207392977;2626.3959289363447;2631.1843328568143;2646.047422927747;4495.572920572548;4584.7478388688605;4660.904687398459;4682.321063445803;4697.835575754849;4604.9539504604945;4653.208954477738;4657.347971284023;4712.434507859061;4715.269133284338;4232.470941132358;4266.2534056540135;4267.210566045276;4307.310830267158;4357.368181613664;4405.7797110144365;4419.447569053868;4442.155990361821;4445.399871017404;4502.662300327472;4177.122804211089;4194.580541945811;4384.956150438518;4407.096545208241;4437.733735975852;4245.341971994346;4252.192083504156;4259.424977628011;4265.274903266452;4271;4244.045089854789;4257.276263035292;4259.957400425987;4262.573742625744;4266.424032755569;4618.237990731525;4638.976805115953;4676.789544470473;4736.147493451177;4748;5099;5099.949000509984;5102.107131252016;5102.744862756885;5160.870978225536;5123.487651234884;5126.239496634929;5132.5124113209185;5136.614753893473;5197.610179236534;4527.117212143667;4580.083983203373;4664.346991421204;4777.163996300634;4790.161721698689;5171.612129090333;5172.250023746536;5172.508611681884;5177.844664660066;5222.738863056832;4904.509549045139;4938.160512712848;4949.455559888409;4951.702897826142;5015;4888.682235654671;4929.950700492949;4932.383452068491;5043.722595257223;5045.167547354718;4978;5013.1227035268685;5041.571466425322;5075.593952483866;5076.796928122833;4986.925196122061;4995.050940321327;5001.4998500149695;5018.623603229772;5036.79852805891;4136.669066474675;4175.853845115406;4298.247806633826;4302.569743025672;4312.741235525878;3683.557973043252;3688.2623475305045;3719.888403347904;3727.4781530585738;3776.4712940188397;4239.003834098984;4283.250431174532;4289.442372491556;4291.871243862689;4344.543822898613;4983.6013118950395;4990;4994.725290109006;4999.65002449825;5050.393952725696;4223.619874211336;4269.935950960738;4280.143971205771;4299.742015479082;4325.870223893288;4553.658475614342;4608.216603177468;4610.95389046112;4612.377329060601;4650.395448591729;4749.667523273407;4758.095961767253;4760.285957106531;4777;4821.035792841516;4687.460941991714;4780.641451891052;5040.873978150429;5059.696418214919;5128.666636668605;4666.486686464418;4704.529547045268;4749.620030397629;4768.61851051922;4807.375041244616;4945.653804233669;4954.900901981868;4958.330625365529;4958.404991401055;5012.724300163526;5006.249062640633;5013.222950442687;5013.799448022039;5026.748662566784;5064.139096353555;4702.858914232578;4747.454042784986;4821.590164836029;4853.611711063036;4934.925976110289;4830.806767729251;4852.344933434082;4855.562999330077;4916.950830491649;4921.975390123026;4787.18617834962;4790.712557246577;4792.904141917212;4793.13723529768;4834.685745426536;4758.666893317434;4761.142994261067;4822.42130944289;4895.6083513318135;4956.504349565014;4738.952610473989;4745.501722319107;4763.499832517536;4807;4808.134535783593 09:47:06 INFO - PROCESS | 1807 | |1;ext.html;549.3298176225006;564.7684449375756;569.0610492687066;571.70557163061;578.8118861370056;28708.56937145942;42362;43933.56066439335;44041.55958440411;44125;41186.38220426688;41772.791136044274;41802.16395672085;41842;42003.789981050126;7585.658645360958;7737.07155141381;7737.264959828807;7738.729144479917;7740.8838867417035;7276.599787011713;7476.36450901674;10057.396556206651;10378.636747713832;10393.428361440156;10075.697729068102;10157.796844063114;10171.54228059739;10176.542055607479;10179.185665146808;10014.899851001506;10069.093781559695;10071.446070466121;10084.899151008543;10090.596376144946;9673.758156046084;9721.756956076084;9732.854007189897;9735;9737.513124343825;38415;38471.422928656095;38627.227455451015;38683.613163868285;38713.22573548513;2824.9717502824915;2874.841883696396;2957.9260518486994;3082.599262095913;3097.7676674249296;28702.425951481062;29107;29215.269618259503;29700.554491682426;30219.244518886982;3142.4500712375243;3204.6635103314047;3226.0644413120167;3229.6124465064345;3255.869765209389;274809;275688;276053;276066;276292;31195.37609247802;52790.47209527932;53137.468625314024;53148;53234.20148697773;31882;32017.519737203962;32072.358552828813;32199.51700724491;32266.35467290688;11242.94378528102;11273.774524509465;11274.492647830868;11290.70156931947;11301.152413569016;8348.874766878502;9116.447394729354;9135.634574617074;9135.725928222164;9146.588403521857;7189.137303523611;7594.848103037908;7726.304632583095;7766.5728384939375;7917.722879699183;2994.8352840593975;3041.224487755608;3078.7229149376662;3085.2441151917474;3111.3621707549896;771.3135309574483;820.6471217376466;859.0550394566052;929.2473096791485;960.2462067277154;27200.727992719818;28166.85916570404;28176.732047057933;28278.575821362698;28649.856750716113;860.9827803443897;918.4397517514311;940.0458534587399;943.8489841625307;977.8044391121804;14590.124592524482;14591.92704036473;14767.113973161644;14945.17801520905;15221.6194595136;371737;376602;376721;376924;377520;49905;50390.748046259534;50427.74786126046;50501;50516.49483505191;1030.7474668706216;1042.9374237545771;1048.6906362623045;1118.7874303882236;1126.2172789911097;27267.318317042234;30707;30833;31263.374732504766;31634.367312654078;47493.76253118712;48015.51984480111;48143.75928120337;48186.27720584124;48199.75900120477;338961;340874;341253;341497;341513;24791.25626231216;25187.370315742253;25575.23274301774;25602.61596076023;25604.23187304384;41630.79184604058;41925.1614967705;41989.79005104955;42146.15707685768;42446.363304549835;35139.29721405506;35358.823205883804;35390.29219415546;35434.822825885705;35559.644403555634;35287;35331.470027949086;35689.82155089312;36051;36108.27783444264;34781.26093695356;35083.91283263861;35384.46923296204;35535.822320888234;35900.28199436049;29674.554881677224;33423.498647519824;33493.66506334906;33845.323093538485;33867.4919876207;24960.500789983736;25276.87361563254;25511;25516.744832551438;25691.35771605725 09:47:06 INFO - PROCESS | 1807 | |2;jquery.html;333.33832342799553;342.48285089514826;346.6793216274946;352.73721077797046;379.0466975556476;10984.121270298378;15660.921695391535;15723.842761572381;17043.233054512544;17258.395956141536;16458.75311870323;16515.917420412912;17231.22459489323;17346.132693365314;17432.47702568922;4793.664443488957;4845.200541910598;5004.899902001957;5026.220935754964;5051.3938327400765;3541.8937431877084;4944.159492886218;5009.398872135349;5019.924701129486;5024.371953505812;3736.5142531471006;5689.630174038674;6000.429959153874;6033.456988871001;6049.637021778685;5076.822311219126;5345.786168553253;5990.221271234734;5999.100134979744;6005.519558435315;5267.367915850123;5269.7365131743645;5271.65734227274;5274.947250527484;5275.28783614211;11135.888641113566;13277.668058298523;20078.397648070437;20143.0935607898;20253.392398228076;1687.7130887749113;1780.777402824647;1851.2039822876159;1870.8971006594631;1973.526353675122;8303.543305118215;8390.160983901622;8732.650693972233;8759.729839173348;8908.064653211384;1684.5199118251362;1699.286299754094;1714.4342367018853;1719.5615118144792;1740.0429763629952;70174;70631;72175.99999999948;72506;73281.63359183223;9779.364341317934;9971.252156088178;9978.850317245247;10116.08955194036;10198.235132365102;13752.724945501033;13757.724845503235;13766.862331376558;13783.310834458238;13790.931045344909;6420.903686444707;6474.5791523550815;6704.731810727516;6715.1941766988275;6785.694643741043;5980;6006.759729610767;6148.200733904564;6161.815145545641;6164.291106522807;4252.29837076885;4256.425382573375;4313.180495705868;4337.934930976038;4429.734215947054;1684.7135986882015;1782.9375971840923;1788.4277031350102;1800;1843.907804609764;766.938644908406;804.9476784009021;810.2424233341844;849.3502470609989;862.7454900804153;16244.675106497803;16602.853115534224;16881.831181688027;16966.830331696525;17081.658366832595;781.5427974634903;802.9799255018671;823.4473133286882;849.668629234599;922.4465320807597;9273;9510.837262749039;9751.512424378752;9806;9892.851607225897;403068;410168;410461;416240;424358;78291;79530.6023469879;79797;80820.19179808244;81598;864.5374724522351;887.3433659092225;888.8800011998427;891.197921870309;893.0489029183898;14283.42866285337;15183.848161518685;15295.464658736892;16451.506454806375;16709.16454177262;34678.82660586681;35914.64085359113;37154.8142259287;38799.418008730456;39465.60534394619;49049;51464.485355147466;52432.737836310574;52862.47137528729;54516.45483545272;6512.967435162984;6673.332666733288;6702.028205910213;6902.24075351721;6928.618925958919;8692.91307086921;11570.94214528922;11647.359395233343;11857.762844743229;12407.813882791947;8671.6097775599;9744.707658769965;9924.900750992687;10041.297109202285;10282.537285822305;9718.75703107428;9774.853377199206;9946.850797238192;10029.398236105857;10223.284370094021;9674.951625241829;9718.319717619699;9767.80464390694;9956.751081222737;10127;6531.3468653134305;6537.313582074004;6690.264070952288;6921.48088893325;7439.442041846992;6970.79087627372;9272.443653380818;9334.953325233331;9385.953070234606;9880.703578892644 09:47:06 INFO - PROCESS | 1807 | |3;mootools.html;1199.4602428906983;1298.9610311690649;1340.5040135149993;1361.8638136186376;1626.1706529669864;6815.148106486689;7562.395008399329;7855.646495907684;7861.646225919833;8087.474314169574;7894.802629934255;7960.1243863175005;7980.960095199516;7990.200979902007;7993.3205677517335;2137.732783402075;2164.6211912915255;2166.6100101981638;2166.696662467256;2167.295628920599;2110.9867263713427;2137.7434707835005;2181.5418762059985;2181.890905454729;2184.4102092435114;2130.509982703979;2149.032935179168;2156.3315372234656;2159.125554150564;2164.8051675349216;2109.915603375871;2125.341144245281;2127.042830726164;2129.307974908161;2135.903884325209;2050.8974551272377;2093;2097.328854766475;2102.9158833646634;2108;7569.659365328518;7814.296713295774;7922.366210703188;7930.801729956739;8037.035555733291;4353.1293741251875;4478.216312145394;4492.797824097891;4496.2581174106235;4532.297493888453;2907.694692057346;2957.053742802304;2993.311538346171;3020.426119037376;3033.8634761435787;956.2636769687302;961.20700422152;961.6345788600357;963.5423173992367;984.2027957354494;10443.947780261125;10456.268061235716;10471.267011309212;10519;10543.683689489328;3975.980120099381;4026.6376026157786;4094.7338423003007;4174.269502836992;4183.623473887304;8366.37252206087;8443.95778021106;8466.407351485337;8470.110638383005;8484.109168537214;4298.290782020994;4299.355096735515;4299.484061912528;4311;4337.631301339421;3477.2871561329866;3572.732045096628;3900.64894159527;4059.7564146151326;4068.2473742357165;3290.851911663981;3345.8996230113135;3455.6026057003764;3458.7060099891287;3464.082018265162;951.1979055829604;990.8315586350337;997.2520609542843;1004.7437903334603;1013.2451323763843;718.7340683946973;749.2956620776453;761.9771406857803;763.9427042971688;784.5096814490943;4926.8768280793265;4999.850004499871;5029;5058.494150584912;5298.099323115002;394.0994826820701;395.70717668925363;399.86204759357975;401.12754758400365;402.7442573965494;3187.2031992002;3509.191808894147;3533.6819686228364;3559.83980720863;3574.6067932527403;9659.130678238993;10029.949850250701;10036.146927511243;10046;10052.195824334036;9932.851007234898;9937.900620993698;9944.900550994542;9988.950055249677;9990.250731195189;7500.174980752113;7587.317141457185;7588;7590.20302868213;7608.391328693691;4929.926051109164;4945.876353091057;5110.872228194325;5174.8965020700125;5350.759215835219;8994.100589940954;9063.63745450201;9126.498042607454;9146.771330716785;9163.496007719372;8944.463332200088;9203.079692030744;9246.167844893991;9268.768280793034;9415.199708024895;6282.503019474259;6345.619262844244;6441.613503189824;6462.450691691259;6478.578892371981;7245.818854528679;7282.089738782653;7298.854022919406;7298.927010729824;7301.926980730124;6588.7035083422315;6748.1227440434395;6750.966245168743;6786.626735529593;6936.098307220029;6605.1083103780375;6621.9337806621315;6646.767363142267;6710.228323742636;6812.897806533005;6048.334683184934;6071.787487437919;6111.633302001894;6115.327313995546;6116.571839971337;4455.5098939117315;4496.797644105957;4584;4595.425571803525;4645.883852903705;4433.268510695699;4467.553244675506;4595.126925884071;4597.747123908217;4701.106789709944 09:47:06 INFO - PROCESS | 1807 | |4;prototype.html;266.4497812018182;276.5285188753176;278.9358596952624;279.04705430953294;283.4288907850681;4636.211843986522;4771.952280477194;4774.427068751755;4827.589654879335;4834.927476087862;4622.930656040162;4728.716277023381;4732.952670473294;4739.028499157673;4763.523647635235;3542.6988705960025;3624.3113808376456;3649.4708267301226;3718.72109591781;3739.383001804699;3745.4381842723674;3748.3815170496837;3754.080250338657;3755.906102347436;3760.4735337052834;3714.554253489585;3724.16206353571;3729.179580492287;3737.588865224823;3738.252349530091;3727.1800203955086;3728.776273423576;3742.419924911643;3747.2505498900327;3748.6063963283873;3183.283761153745;3243.8864639737494;3255.1048461672995;3258.3809076275434;3260.608726952757;4604.516525764813;4618.792154353062;4625.514320996281;4631.235846085392;4656.138614356321;4076.408920706511;4230.428892099558;4233.364995250707;4237.470316210473;4237.9022383920155;4916.975415122937;4953.455119936804;4979.975100124512;4980.975095124538;4997.30037794709;3516.033090899997;3522.5949015862993;3528.523649307336;3545.663161999606;3569.6430356964356;5193.714345710984;5222.268882356473;5225.634205605609;5227.529522343008;5232.136697444917;3435.783919372041;3735.7945313007504;3737.8507358269103;3761.8683346082757;3766.6986641068647;3778.4332350147147;3794.335991201584;3810.447485114643;3810.48558444612;3812.17015832402;4264;4288.185244803477;4294.570542945743;4300.720453170597;4302.139572085533;4728;4783.330333753277;4873.853784386474;4997.90004199914;5001.89996200074;1305.7649623067748;1318.228836130855;1318.8944884409225;1319.7198717244257;1320.2540564580981;811.7848770075934;854.495847449995;858.1932982995963;861.2679222660688;865.5455885660078;3960.5643379228263;3969.2656858481273;3975.6223158799867;3983.342748446532;3990.4612877261197;4517.537941821715;4531.320301954733;4535.63714902807;4538.298138198175;4538.88941642138;3958.030282580786;3967.3850553164307;3977.2642061218767;3994.460747799068;4007.278689835799;2268.6256767633486;2411.963820542693;2415.9154429595233;2424.757524247561;2454.9509009819703;4362.451329777947;4373.2565463871215;4377.9781101094295;4379.255526560429;4384.69307148503;4675.2052151134385;4679.836205732783;4690.296455531698;4692.41344831896;4693.272542755878;4159.438475805728;4338.139056239886;4370.431843860278;4370.912581748411;4378.824847006148;4561.035327037453;4563.330451685668;4576.656750743641;4580.244259697112;4580.381648477412;4477.776111194493;4514.171760436897;4515.34527493505;4528.728276303301;4554.385158003627;4140.896477588084;4315.697901147015;4321.978390108029;4333.783310834383;4338.913221735611;3381.9492707608915;3395.966040339565;3402.8468718907216;3429.5713035870517;3437.5249820129466;4532.297493888453;4566.337881007169;4589.2657174851865;4846.975765121152;5002.774875130701;4864.294677271847;4969.279454479152;5013.423456302572;5021.4225364083595;5031.949680503149;4960.23116416956;5027.522385373383;5034.798608055637;5035.395752509722;5048.343715316985;4723.244280915037;4887.706737595613;4895.360054381822;4951.826686066114;4953.702777833341;4995.850124496271;5018.397792264952;5056.519630635084;5062.670926389773;5064.265681476239;4937.284093806306;4944.8847226247235;4946.3817022872145;4958.785097651099;4959.677620954626 09:47:06 INFO - PROCESS | 1807 | |5;yui.html;550.2956216043466;580.9680467574287;584.2930054633892;625.7425742574258;653.5555822041011;11830.940845295772;12402.255864648127;12422.93788531056;12439.68900777481;12440.564580239705;11970.28178309302;12028.218165819215;12032.578859739911;12034.879651203486;12036.759264814698;584.2200662116059;600.7777122464694;716.57721944053;736.1166600079926;739.0355585960319;713.5468977199464;733.3986131372277;737.2443245673201;740.2634378793108;740.6518936100051;781.8006408365859;784.0904550721165;784.2353705137502;787.2049230277404;788.7830846517196;607.1469585232755;626.4862812493757;778.2101167315179;778.5562229529171;778.8208711996273;547.9561635069185;562.3420597900466;568.9943100568983;569.4106599669323;569.422036632818;12487.06347023977;12521.874781252161;12522.123451358408;12522.248665080124;12525.686857828536;11583.305001699924;11838.171328007043;11869.46587403569;12032.186746867887;13055.54305599309;9445.149936505679;9584.185344245747;11683.766324673545;11831.290122592673;11871.169018168732;3945.3490174121234;4049.190161967618;4077.755334679899;4089.8159582818844;4235.0682849773;58722;58805.70597146987;59436.70281648564;59887.40112598862;60642.69678651623;9995.200383969264;10094.747631309128;10132.645357412455;10160.441175735257;10324.43215623148;17663.558411039827;17919.820801791815;18088.276468941094;18132.818671813377;18163;6456.0961465394885;6524.282328943812;6526.836829079216;6624.072629831828;6822.590644561243;3578.3380074686265;3652.2878038782183;3668.174660701321;3718.5537735471376;3789.6778773803985;2591.663083799132;2720.8911643534434;2721.251655794633;2722.605222242764;2723.319170207448;459.8574441922948;460.07984031936127;465.12556393979776;468.68129671822805;475.69793181330107;428.12662169174536;433.4863187123269;441.1441802902359;453.0825079214551;472.0605994071841;6688.428219368445;6701.463882889358;7024.157101147793;7046.647667616701;7195.496315257987;456.06051533840395;460.19006548474925;464.3615029334665;483.8935434204469;504.6591563847354;3283.7044665979697;3360.630330663625;3476.1228936374073;3585.8924232273075;3823.885283441501;98991;100159;101193.49403253084;102124;104765.47617261866;87760;89530.55234723784;91036.54481727681;93221.5338923301;93465;49130.50869491331;49137.754311228215;49154.75422622936;52268;73345.63327183436;6769.796906092825;6800.761973330909;6912;7003.642213706416;7014.68433920485;47617.52382476131;48136.75931620319;48731;48775.5122448771;50334.244986324506;59626.403735962085;68675;70163;72987.63506182435;73108;8324.583770811556;8376.87434688468;8455.112213217524;8693.652253909773;8820;8612.48325100496;8770.342224333073;8869.68956086534;8965.282777377664;9043.864342034743;8676.379971774244;8857.601407936787;8883.600237989176;9097.681581144629;9140.725778226664;8534.615942282488;8554.358423118167;8555.101714319906;8689;9063.546822658973;7689.461737678309;7733.304002639789;7764.456488045783;7796.649150788115;7886.960565197367;7463.701451941862;7623.16145224036;7631.427642926692;7762.5730584818375;7960.442769006114;7225.747098851515;7568.5458872467825;7711.768646940601;7723.961380193063;8058.798530036796 09:47:06 INFO - PROCESS | 1807 | __end_tp_report 09:47:06 INFO - PROCESS | 1807 | __start_cc_report 09:47:06 INFO - PROCESS | 1807 | _x_x_mozilla_cycle_collect,1250 09:47:06 INFO - PROCESS | 1807 | __end_cc_report 09:47:06 INFO - PROCESS | 1807 | __startTimestamp1474130826817__endTimestamp 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | ------- Summary: start ------- 09:47:06 INFO - PROCESS | 1807 | Number of tests: 6 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#0] dojo.html Cycles:165 Average:4633.72 Median:4758.10 stddev:493.17 (10.4%) stddev-sans-first:467.29 09:47:06 INFO - PROCESS | 1807 | Values: 2567.7 2588.3 2626.4 2631.2 2646.0 4495.6 4584.7 4660.9 4682.3 4697.8 4605.0 4653.2 4657.3 4712.4 4715.3 4232.5 4266.3 4267.2 4307.3 4357.4 4405.8 4419.4 4442.2 4445.4 4502.7 4177.1 4194.6 4385.0 4407.1 4437.7 4245.3 4252.2 4259.4 4265.3 4271.0 4244.0 4257.3 4260.0 4262.6 4266.4 4618.2 4639.0 4676.8 4736.1 4748.0 5099.0 5099.9 5102.1 5102.7 5160.9 5123.5 5126.2 5132.5 5136.6 5197.6 4527.1 4580.1 4664.3 4777.2 4790.2 5171.6 5172.3 5172.5 5177.8 5222.7 4904.5 4938.2 4949.5 4951.7 5015.0 4888.7 4930.0 4932.4 5043.7 5045.2 4978.0 5013.1 5041.6 5075.6 5076.8 4986.9 4995.1 5001.5 5018.6 5036.8 4136.7 4175.9 4298.2 4302.6 4312.7 3683.6 3688.3 3719.9 3727.5 3776.5 4239.0 4283.3 4289.4 4291.9 4344.5 4983.6 4990.0 4994.7 4999.7 5050.4 4223.6 4269.9 4280.1 4299.7 4325.9 4553.7 4608.2 4611.0 4612.4 4650.4 4749.7 4758.1 4760.3 4777.0 4821.0 4687.5 4780.6 5040.9 5059.7 5128.7 4666.5 4704.5 4749.6 4768.6 4807.4 4945.7 4954.9 4958.3 4958.4 5012.7 5006.2 5013.2 5013.8 5026.7 5064.1 4702.9 4747.5 4821.6 4853.6 4934.9 4830.8 4852.3 4855.6 4917.0 4922.0 4787.2 4790.7 4792.9 4793.1 4834.7 4758.7 4761.1 4822.4 4895.6 4956.5 4739.0 4745.5 4763.5 4807.0 4808.1 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#1] ext.html Cycles:180 Average:47627.90 Median:34932.59 stddev:87889.67 (251.6%) stddev-sans-first:88065.14 09:47:06 INFO - PROCESS | 1807 | Values: 549.3 564.8 569.1 571.7 578.8 28708.6 42362.0 43933.6 44041.6 44125.0 41186.4 41772.8 41802.2 41842.0 42003.8 7585.7 7737.1 7737.3 7738.7 7740.9 7276.6 7476.4 10057.4 10378.6 10393.4 10075.7 10157.8 10171.5 10176.5 10179.2 10014.9 10069.1 10071.4 10084.9 10090.6 9673.8 9721.8 9732.9 9735.0 9737.5 38415.0 38471.4 38627.2 38683.6 38713.2 2825.0 2874.8 2957.9 3082.6 3097.8 28702.4 29107.0 29215.3 29700.6 30219.2 3142.5 3204.7 3226.1 3229.6 3255.9 274809.0 275688.0 276053.0 276066.0 276292.0 31195.4 52790.5 53137.5 53148.0 53234.2 31882.0 32017.5 32072.4 32199.5 32266.4 11242.9 11273.8 11274.5 11290.7 11301.2 8348.9 9116.4 9135.6 9135.7 9146.6 7189.1 7594.8 7726.3 7766.6 7917.7 2994.8 3041.2 3078.7 3085.2 3111.4 771.3 820.6 859.1 929.2 960.2 27200.7 28166.9 28176.7 28278.6 28649.9 861.0 918.4 940.0 943.8 977.8 14590.1 14591.9 14767.1 14945.2 15221.6 371737.0 376602.0 376721.0 376924.0 377520.0 49905.0 50390.7 50427.7 50501.0 50516.5 1030.7 1042.9 1048.7 1118.8 1126.2 27267.3 30707.0 30833.0 31263.4 31634.4 47493.8 48015.5 48143.8 48186.3 48199.8 338961.0 340874.0 341253.0 341497.0 341513.0 24791.3 25187.4 25575.2 25602.6 25604.2 41630.8 41925.2 41989.8 42146.2 42446.4 35139.3 35358.8 35390.3 35434.8 35559.6 35287.0 35331.5 35689.8 36051.0 36108.3 34781.3 35083.9 35384.5 35535.8 35900.3 29674.6 33423.5 33493.7 33845.3 33867.5 24960.5 25276.9 25511.0 25516.7 25691.4 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#2] jquery.html Cycles:180 Average:24784.14 Median:28850.05 stddev:68238.14 (236.5%) stddev-sans-first:68404.87 09:47:06 INFO - PROCESS | 1807 | Values: 333.3 342.5 346.7 352.7 379.0 10984.1 15660.9 15723.8 17043.2 17258.4 16458.8 16515.9 17231.2 17346.1 17432.5 4793.7 4845.2 5004.9 5026.2 5051.4 3541.9 4944.2 5009.4 5019.9 5024.4 3736.5 5689.6 6000.4 6033.5 6049.6 5076.8 5345.8 5990.2 5999.1 6005.5 5267.4 5269.7 5271.7 5274.9 5275.3 11135.9 13277.7 20078.4 20143.1 20253.4 1687.7 1780.8 1851.2 1870.9 1973.5 8303.5 8390.2 8732.7 8759.7 8908.1 1684.5 1699.3 1714.4 1719.6 1740.0 70174.0 70631.0 72176.0 72506.0 73281.6 9779.4 9971.3 9978.9 10116.1 10198.2 13752.7 13757.7 13766.9 13783.3 13790.9 6420.9 6474.6 6704.7 6715.2 6785.7 5980.0 6006.8 6148.2 6161.8 6164.3 4252.3 4256.4 4313.2 4337.9 4429.7 1684.7 1782.9 1788.4 1800.0 1843.9 766.9 804.9 810.2 849.4 862.7 16244.7 16602.9 16881.8 16966.8 17081.7 781.5 803.0 823.4 849.7 922.4 9273.0 9510.8 9751.5 9806.0 9892.9 403068.0 410168.0 410461.0 416240.0 424358.0 78291.0 79530.6 79797.0 80820.2 81598.0 864.5 887.3 888.9 891.2 893.0 14283.4 15183.8 15295.5 16451.5 16709.2 34678.8 35914.6 37154.8 38799.4 39465.6 49049.0 51464.5 52432.7 52862.5 54516.5 6513.0 6673.3 6702.0 6902.2 6928.6 8692.9 11570.9 11647.4 11857.8 12407.8 8671.6 9744.7 9924.9 10041.3 10282.5 9718.8 9774.9 9946.9 10029.4 10223.3 9675.0 9718.3 9767.8 9956.8 10127.0 6531.3 6537.3 6690.3 6921.5 7439.4 6970.8 9272.4 9335.0 9386.0 9880.7 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#3] mootools.html Cycles:180 Average:5066.99 Median:4813.99 stddev:2937.89 (61.0%) stddev-sans-first:2931.75 09:47:06 INFO - PROCESS | 1807 | Values: 1199.5 1299.0 1340.5 1361.9 1626.2 6815.1 7562.4 7855.6 7861.6 8087.5 7894.8 7960.1 7981.0 7990.2 7993.3 2137.7 2164.6 2166.6 2166.7 2167.3 2111.0 2137.7 2181.5 2181.9 2184.4 2130.5 2149.0 2156.3 2159.1 2164.8 2109.9 2125.3 2127.0 2129.3 2135.9 2050.9 2093.0 2097.3 2102.9 2108.0 7569.7 7814.3 7922.4 7930.8 8037.0 4353.1 4478.2 4492.8 4496.3 4532.3 2907.7 2957.1 2993.3 3020.4 3033.9 956.3 961.2 961.6 963.5 984.2 10443.9 10456.3 10471.3 10519.0 10543.7 3976.0 4026.6 4094.7 4174.3 4183.6 8366.4 8444.0 8466.4 8470.1 8484.1 4298.3 4299.4 4299.5 4311.0 4337.6 3477.3 3572.7 3900.6 4059.8 4068.2 3290.9 3345.9 3455.6 3458.7 3464.1 951.2 990.8 997.3 1004.7 1013.2 718.7 749.3 762.0 763.9 784.5 4926.9 4999.9 5029.0 5058.5 5298.1 394.1 395.7 399.9 401.1 402.7 3187.2 3509.2 3533.7 3559.8 3574.6 9659.1 10029.9 10036.1 10046.0 10052.2 9932.9 9937.9 9944.9 9989.0 9990.3 7500.2 7587.3 7588.0 7590.2 7608.4 4929.9 4945.9 5110.9 5174.9 5350.8 8994.1 9063.6 9126.5 9146.8 9163.5 8944.5 9203.1 9246.2 9268.8 9415.2 6282.5 6345.6 6441.6 6462.5 6478.6 7245.8 7282.1 7298.9 7298.9 7301.9 6588.7 6748.1 6751.0 6786.6 6936.1 6605.1 6621.9 6646.8 6710.2 6812.9 6048.3 6071.8 6111.6 6115.3 6116.6 4455.5 4496.8 4584.0 4595.4 4645.9 4433.3 4467.6 4595.1 4597.7 4701.1 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#4] prototype.html Cycles:180 Average:3997.06 Median:4375.62 stddev:1146.20 (26.2%) stddev-sans-first:1114.69 09:47:06 INFO - PROCESS | 1807 | Values: 266.4 276.5 278.9 279.0 283.4 4636.2 4772.0 4774.4 4827.6 4834.9 4622.9 4728.7 4733.0 4739.0 4763.5 3542.7 3624.3 3649.5 3718.7 3739.4 3745.4 3748.4 3754.1 3755.9 3760.5 3714.6 3724.2 3729.2 3737.6 3738.3 3727.2 3728.8 3742.4 3747.3 3748.6 3183.3 3243.9 3255.1 3258.4 3260.6 4604.5 4618.8 4625.5 4631.2 4656.1 4076.4 4230.4 4233.4 4237.5 4237.9 4917.0 4953.5 4980.0 4981.0 4997.3 3516.0 3522.6 3528.5 3545.7 3569.6 5193.7 5222.3 5225.6 5227.5 5232.1 3435.8 3735.8 3737.9 3761.9 3766.7 3778.4 3794.3 3810.4 3810.5 3812.2 4264.0 4288.2 4294.6 4300.7 4302.1 4728.0 4783.3 4873.9 4997.9 5001.9 1305.8 1318.2 1318.9 1319.7 1320.3 811.8 854.5 858.2 861.3 865.5 3960.6 3969.3 3975.6 3983.3 3990.5 4517.5 4531.3 4535.6 4538.3 4538.9 3958.0 3967.4 3977.3 3994.5 4007.3 2268.6 2412.0 2415.9 2424.8 2455.0 4362.5 4373.3 4378.0 4379.3 4384.7 4675.2 4679.8 4690.3 4692.4 4693.3 4159.4 4338.1 4370.4 4370.9 4378.8 4561.0 4563.3 4576.7 4580.2 4580.4 4477.8 4514.2 4515.3 4528.7 4554.4 4140.9 4315.7 4322.0 4333.8 4338.9 3381.9 3396.0 3402.8 3429.6 3437.5 4532.3 4566.3 4589.3 4847.0 5002.8 4864.3 4969.3 5013.4 5021.4 5031.9 4960.2 5027.5 5034.8 5035.4 5048.3 4723.2 4887.7 4895.4 4951.8 4953.7 4995.9 5018.4 5056.5 5062.7 5064.3 4937.3 4944.9 4946.4 4958.8 4959.7 09:47:06 INFO - PROCESS | 1807 | 09:47:06 INFO - PROCESS | 1807 | [#5] yui.html Cycles:180 Average:16946.71 Median:59121.20 stddev:25796.98 (43.6%) stddev-sans-first:25839.97 09:47:06 INFO - PROCESS | 1807 | Values: 550.3 581.0 584.3 625.7 653.6 11830.9 12402.3 12422.9 12439.7 12440.6 11970.3 12028.2 12032.6 12034.9 12036.8 584.2 600.8 716.6 736.1 739.0 713.5 733.4 737.2 740.3 740.7 781.8 784.1 784.2 787.2 788.8 607.1 626.5 778.2 778.6 778.8 548.0 562.3 569.0 569.4 569.4 12487.1 12521.9 12522.1 12522.2 12525.7 11583.3 11838.2 11869.5 12032.2 13055.5 9445.1 9584.2 11683.8 11831.3 11871.2 3945.3 4049.2 4077.8 4089.8 4235.1 58722.0 58805.7 59436.7 59887.4 60642.7 9995.2 10094.7 10132.6 10160.4 10324.4 17663.6 17919.8 18088.3 18132.8 18163.0 6456.1 6524.3 6526.8 6624.1 6822.6 3578.3 3652.3 3668.2 3718.6 3789.7 2591.7 2720.9 2721.3 2722.6 2723.3 459.9 460.1 465.1 468.7 475.7 428.1 433.5 441.1 453.1 472.1 6688.4 6701.5 7024.2 7046.6 7195.5 456.1 460.2 464.4 483.9 504.7 3283.7 3360.6 3476.1 3585.9 3823.9 98991.0 100159.0 101193.5 102124.0 104765.5 87760.0 89530.6 91036.5 93221.5 93465.0 49130.5 49137.8 49154.8 52268.0 73345.6 6769.8 6800.8 6912.0 7003.6 7014.7 47617.5 48136.8 48731.0 48775.5 50334.2 59626.4 68675.0 70163.0 72987.6 73108.0 8324.6 8376.9 8455.1 8693.7 8820.0 8612.5 8770.3 8869.7 8965.3 9043.9 8676.4 8857.6 8883.6 9097.7 9140.7 8534.6 8554.4 8555.1 8689.0 9063.5 7689.5 7733.3 7764.5 7796.6 7887.0 7463.7 7623.2 7631.4 7762.6 7960.4 7225.7 7568.5 7711.8 7724.0 8058.8 09:47:06 INFO - PROCESS | 1807 | -------- Summary: end -------- 09:47:06 INFO - PROCESS | 1807 | 09:47:07 INFO - TEST-INFO | 1807: exit 0 09:47:07 INFO - TEST-OK | dromaeo_css | took 1121016ms 09:47:07 INFO - TEST-START | kraken 09:47:07 INFO - operating with platform_type : linux_ 09:47:07 INFO - Initialising browser for kraken test... 09:47:07 INFO - TEST-INFO | started process 2110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA4n9Ou/profile http://localhost:45383/getInfo.html) 09:47:12 INFO - PROCESS | 2110 | __metrics Screen width/height:1600/1200 09:47:12 INFO - PROCESS | 2110 | colorDepth:24 09:47:12 INFO - PROCESS | 2110 | Browser inner width/height: 1024/697 09:47:12 INFO - PROCESS | 2110 | __metrics 09:47:12 INFO - PROCESS | 2110 | 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" 09:47:12 INFO - PROCESS | 2110 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:47:13 INFO - PROCESS | 2110 | console.error: PushService: 09:47:13 INFO - PROCESS | 2110 | stateChangeProcessEnqueue: Error transitioning state 09:47:13 INFO - PROCESS | 2110 | UnknownError 09:47:14 INFO - TEST-INFO | 2110: exit 0 09:47:14 INFO - Browser initialized. 09:47:14 INFO - Running cycle 1/1 for kraken test... 09:47:14 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA4n9Ou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:26 INFO - PROCESS | 2191 | RSS: Main: 174141440 09:47:26 INFO - PROCESS | 2191 | 09:47:29 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45383/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:30 INFO - PROCESS | 2191 | RSS: Main: 237404160 09:47:30 INFO - PROCESS | 2191 | 09:47:32 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45383/tests/kraken/driver.html?testName=audio-dft) 09:47:33 INFO - PROCESS | 2191 | RSS: Main: 218038272 09:47:33 INFO - PROCESS | 2191 | 09:47:36 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45383/tests/kraken/driver.html?testName=audio-fft) 09:47:37 INFO - PROCESS | 2191 | RSS: Main: 299950080 09:47:37 INFO - PROCESS | 2191 | 09:47:39 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45383/tests/kraken/driver.html?testName=audio-oscillator) 09:47:40 INFO - PROCESS | 2191 | RSS: Main: 210259968 09:47:40 INFO - PROCESS | 2191 | 09:47:42 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45383/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:42 INFO - PROCESS | 2191 | RSS: Main: 213426176 09:47:42 INFO - PROCESS | 2191 | 09:47:46 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45383/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:46 INFO - PROCESS | 2191 | RSS: Main: 249765888 09:47:46 INFO - PROCESS | 2191 | 09:47:50 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45383/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:50 INFO - PROCESS | 2191 | RSS: Main: 244420608 09:47:50 INFO - PROCESS | 2191 | 09:47:53 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45383/tests/kraken/driver.html?testName=json-parse-financial) 09:47:54 INFO - PROCESS | 2191 | RSS: Main: 246673408 09:47:54 INFO - PROCESS | 2191 | 09:47:55 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45383/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:56 INFO - PROCESS | 2191 | RSS: Main: 209711104 09:47:56 INFO - PROCESS | 2191 | 09:47:58 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:59 INFO - PROCESS | 2191 | RSS: Main: 217600000 09:47:59 INFO - PROCESS | 2191 | 09:48:01 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:48:02 INFO - PROCESS | 2191 | RSS: Main: 222633984 09:48:02 INFO - PROCESS | 2191 | 09:48:04 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:48:05 INFO - PROCESS | 2191 | RSS: Main: 223801344 09:48:05 INFO - PROCESS | 2191 | 09:48:08 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:48:09 INFO - PROCESS | 2191 | RSS: Main: 221827072 09:48:09 INFO - PROCESS | 2191 | 09:48:10 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:45383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45383/tests/kraken/driver.html?testName=ai-astar) 09:48:11 INFO - PROCESS | 2191 | RSS: Main: 273006592 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | __start_tp_report 09:48:11 INFO - PROCESS | 2191 | _x_x_mozilla_page_load 09:48:11 INFO - PROCESS | 2191 | _x_x_mozilla_page_load_details 09:48:11 INFO - PROCESS | 2191 | |i|pagename|runs| 09:48:11 INFO - PROCESS | 2191 | |0;ai-astar;113;116;115;115;117;117;115;115;115;116 09:48:11 INFO - PROCESS | 2191 | |1;audio-beat-detection;162;125;125;161;126;125;124;125;164;127 09:48:11 INFO - PROCESS | 2191 | |2;audio-dft;161;161;161;161;161;160;162;163;164;161 09:48:11 INFO - PROCESS | 2191 | |3;audio-fft;74;75;76;114;77;79;79;76;107;77 09:48:11 INFO - PROCESS | 2191 | |4;audio-oscillator;84;84;111;85;83;84;112;84;84;112 09:48:11 INFO - PROCESS | 2191 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;116;116 09:48:11 INFO - PROCESS | 2191 | |6;imaging-darkroom;146;147;146;148;147;147;146;146;146;147 09:48:11 INFO - PROCESS | 2191 | |7;imaging-desaturate;94;94;95;95;95;95;95;95;95;95 09:48:11 INFO - PROCESS | 2191 | |8;json-parse-financial;66;64;64;64;65;67;66;65;67;67 09:48:11 INFO - PROCESS | 2191 | |9;json-stringify-tinderbox;65;66;66;65;67;65;66;66;65;66 09:48:11 INFO - PROCESS | 2191 | |10;stanford-crypto-aes;83;82;82;81;83;83;89;81;81;82 09:48:11 INFO - PROCESS | 2191 | |11;stanford-crypto-ccm;134;148;150;138;132;133;132;135;167;135 09:48:11 INFO - PROCESS | 2191 | |12;stanford-crypto-pbkdf2;178;177;176;175;177;181;174;176;177;176 09:48:11 INFO - PROCESS | 2191 | |13;stanford-crypto-sha256-iterative;69;70;70;70;69;69;69;69;67;67 09:48:11 INFO - PROCESS | 2191 | __end_tp_report 09:48:11 INFO - PROCESS | 2191 | __start_cc_report 09:48:11 INFO - PROCESS | 2191 | _x_x_mozilla_cycle_collect,1907 09:48:11 INFO - PROCESS | 2191 | __end_cc_report 09:48:11 INFO - PROCESS | 2191 | __startTimestamp1474130891079__endTimestamp 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | ------- Summary: start ------- 09:48:11 INFO - PROCESS | 2191 | Number of tests: 14 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#0] ai-astar Cycles:10 Average:115.40 Median:115.50 stddev:1.17 (1.0%) stddev-sans-first:0.87 09:48:11 INFO - PROCESS | 2191 | Values: 113.0 116.0 115.0 115.0 117.0 117.0 115.0 115.0 115.0 116.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:126.50 stddev:17.93 (14.2%) stddev-sans-first:16.45 09:48:11 INFO - PROCESS | 2191 | Values: 162.0 125.0 125.0 161.0 126.0 125.0 124.0 125.0 164.0 127.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:1.18 (0.7%) stddev-sans-first:1.24 09:48:11 INFO - PROCESS | 2191 | Values: 161.0 161.0 161.0 161.0 161.0 160.0 162.0 163.0 164.0 161.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#3] audio-fft Cycles:10 Average:83.40 Median:76.50 stddev:14.46 (18.9%) stddev-sans-first:14.93 09:48:11 INFO - PROCESS | 2191 | Values: 74.0 75.0 76.0 114.0 77.0 79.0 79.0 76.0 107.0 77.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:84.00 stddev:13.38 (15.9%) stddev-sans-first:13.85 09:48:11 INFO - PROCESS | 2191 | Values: 84.0 84.0 111.0 85.0 83.0 84.0 112.0 84.0 84.0 112.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:48:11 INFO - PROCESS | 2191 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 09:48:11 INFO - PROCESS | 2191 | Values: 146.0 147.0 146.0 148.0 147.0 147.0 146.0 146.0 146.0 147.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:48:11 INFO - PROCESS | 2191 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:1.27 (1.9%) stddev-sans-first:1.33 09:48:11 INFO - PROCESS | 2191 | Values: 66.0 64.0 64.0 64.0 65.0 67.0 66.0 65.0 67.0 67.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 09:48:11 INFO - PROCESS | 2191 | Values: 65.0 66.0 66.0 65.0 67.0 65.0 66.0 66.0 65.0 66.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:2.36 (2.9%) stddev-sans-first:2.50 09:48:11 INFO - PROCESS | 2191 | Values: 83.0 82.0 82.0 81.0 83.0 83.0 89.0 81.0 81.0 82.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:136.50 stddev:11.35 (8.3%) stddev-sans-first:11.79 09:48:11 INFO - PROCESS | 2191 | Values: 134.0 148.0 150.0 138.0 132.0 133.0 132.0 135.0 167.0 135.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:177.00 stddev:1.89 (1.1%) stddev-sans-first:1.94 09:48:11 INFO - PROCESS | 2191 | Values: 178.0 177.0 176.0 175.0 177.0 181.0 174.0 176.0 177.0 176.0 09:48:11 INFO - PROCESS | 2191 | 09:48:11 INFO - PROCESS | 2191 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 09:48:11 INFO - PROCESS | 2191 | Values: 69.0 70.0 70.0 70.0 69.0 69.0 69.0 69.0 67.0 67.0 09:48:11 INFO - PROCESS | 2191 | -------- Summary: end -------- 09:48:11 INFO - PROCESS | 2191 | 09:48:12 INFO - TEST-INFO | 2191: exit 0 09:48:12 INFO - TEST-OK | kraken | took 64523ms 09:48:12 INFO - SUITE-END | took 1185s 09:48:12 INFO - Completed test suite (00:19:45) 09:48:12 INFO - javascript benchmark 09:48:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.666203393559, 2588.2882207392977, 2626.3959289363447, 2631.1843328568143, 2646.047422927747, 4495.572920572548, 4584.7478388688605, 4660.904687398459, 4682.321063445803, 4697.835575754849, 4604.9539504604945, 4653.208954477738, 4657.347971284023, 4712.434507859061, 4715.269133284338, 4232.470941132358, 4266.2534056540135, 4267.210566045276, 4307.310830267158, 4357.368181613664, 4405.7797110144365, 4419.447569053868, 4442.155990361821, 4445.399871017404, 4502.662300327472, 4177.122804211089, 4194.580541945811, 4384.956150438518, 4407.096545208241, 4437.733735975852, 4245.341971994346, 4252.192083504156, 4259.424977628011, 4265.274903266452, 4271.0, 4244.045089854789, 4257.276263035292, 4259.957400425987, 4262.573742625744, 4266.424032755569, 4618.237990731525, 4638.976805115953, 4676.789544470473, 4736.147493451177, 4748.0, 5099.0, 5099.949000509984, 5102.107131252016, 5102.744862756885, 5160.870978225536, 5123.487651234884, 5126.239496634929, 5132.5124113209185, 5136.614753893473, 5197.610179236534, 4527.117212143667, 4580.083983203373, 4664.346991421204, 4777.163996300634, 4790.161721698689, 5171.612129090333, 5172.250023746536, 5172.508611681884, 5177.844664660066, 5222.738863056832, 4904.509549045139, 4938.160512712848, 4949.455559888409, 4951.702897826142, 5015.0, 4888.682235654671, 4929.950700492949, 4932.383452068491, 5043.722595257223, 5045.167547354718, 4978.0, 5013.1227035268685, 5041.571466425322, 5075.593952483866, 5076.796928122833, 4986.925196122061, 4995.050940321327, 5001.4998500149695, 5018.623603229772, 5036.79852805891, 4136.669066474675, 4175.853845115406, 4298.247806633826, 4302.569743025672, 4312.741235525878, 3683.557973043252, 3688.2623475305045, 3719.888403347904, 3727.4781530585738, 3776.4712940188397, 4239.003834098984, 4283.250431174532, 4289.442372491556, 4291.871243862689, 4344.543822898613, 4983.6013118950395, 4990.0, 4994.725290109006, 4999.65002449825, 5050.393952725696, 4223.619874211336, 4269.935950960738, 4280.143971205771, 4299.742015479082, 4325.870223893288, 4553.658475614342, 4608.216603177468, 4610.95389046112, 4612.377329060601, 4650.395448591729, 4749.667523273407, 4758.095961767253, 4760.285957106531, 4777.0, 4821.035792841516, 4687.460941991714, 4780.641451891052, 5040.873978150429, 5059.696418214919, 5128.666636668605, 4666.486686464418, 4704.529547045268, 4749.620030397629, 4768.61851051922, 4807.375041244616, 4945.653804233669, 4954.900901981868, 4958.330625365529, 4958.404991401055, 5012.724300163526, 5006.249062640633, 5013.222950442687, 5013.799448022039, 5026.748662566784, 5064.139096353555, 4702.858914232578, 4747.454042784986, 4821.590164836029, 4853.611711063036, 4934.925976110289, 4830.806767729251, 4852.344933434082, 4855.562999330077, 4916.950830491649, 4921.975390123026, 4787.18617834962, 4790.712557246577, 4792.904141917212, 4793.13723529768, 4834.685745426536, 4758.666893317434, 4761.142994261067, 4822.42130944289, 4895.6083513318135, 4956.504349565014, 4738.952610473989, 4745.501722319107, 4763.499832517536, 4807.0, 4808.134535783593], "value": 4602.007399745532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.3298176225006, 564.7684449375756, 569.0610492687066, 571.70557163061, 578.8118861370056, 28708.56937145942, 42362.0, 43933.56066439335, 44041.55958440411, 44125.0, 41186.38220426688, 41772.791136044274, 41802.16395672085, 41842.0, 42003.789981050126, 7585.658645360958, 7737.07155141381, 7737.264959828807, 7738.729144479917, 7740.8838867417035, 7276.599787011713, 7476.36450901674, 10057.396556206651, 10378.636747713832, 10393.428361440156, 10075.697729068102, 10157.796844063114, 10171.54228059739, 10176.542055607479, 10179.185665146808, 10014.899851001506, 10069.093781559695, 10071.446070466121, 10084.899151008543, 10090.596376144946, 9673.758156046084, 9721.756956076084, 9732.854007189897, 9735.0, 9737.513124343825, 38415.0, 38471.422928656095, 38627.227455451015, 38683.613163868285, 38713.22573548513, 2824.9717502824915, 2874.841883696396, 2957.9260518486994, 3082.599262095913, 3097.7676674249296, 28702.425951481062, 29107.0, 29215.269618259503, 29700.554491682426, 30219.244518886982, 3142.4500712375243, 3204.6635103314047, 3226.0644413120167, 3229.6124465064345, 3255.869765209389, 274809.0, 275688.0, 276053.0, 276066.0, 276292.0, 31195.37609247802, 52790.47209527932, 53137.468625314024, 53148.0, 53234.20148697773, 31882.0, 32017.519737203962, 32072.358552828813, 32199.51700724491, 32266.35467290688, 11242.94378528102, 11273.774524509465, 11274.492647830868, 11290.70156931947, 11301.152413569016, 8348.874766878502, 9116.447394729354, 9135.634574617074, 9135.725928222164, 9146.588403521857, 7189.137303523611, 7594.848103037908, 7726.304632583095, 7766.5728384939375, 7917.722879699183, 2994.8352840593975, 3041.224487755608, 3078.7229149376662, 3085.2441151917474, 3111.3621707549896, 771.3135309574483, 820.6471217376466, 859.0550394566052, 929.2473096791485, 960.2462067277154, 27200.727992719818, 28166.85916570404, 28176.732047057933, 28278.575821362698, 28649.856750716113, 860.9827803443897, 918.4397517514311, 940.0458534587399, 943.8489841625307, 977.8044391121804, 14590.124592524482, 14591.92704036473, 14767.113973161644, 14945.17801520905, 15221.6194595136, 371737.0, 376602.0, 376721.0, 376924.0, 377520.0, 49905.0, 50390.748046259534, 50427.74786126046, 50501.0, 50516.49483505191, 1030.7474668706216, 1042.9374237545771, 1048.6906362623045, 1118.7874303882236, 1126.2172789911097, 27267.318317042234, 30707.0, 30833.0, 31263.374732504766, 31634.367312654078, 47493.76253118712, 48015.51984480111, 48143.75928120337, 48186.27720584124, 48199.75900120477, 338961.0, 340874.0, 341253.0, 341497.0, 341513.0, 24791.25626231216, 25187.370315742253, 25575.23274301774, 25602.61596076023, 25604.23187304384, 41630.79184604058, 41925.1614967705, 41989.79005104955, 42146.15707685768, 42446.363304549835, 35139.29721405506, 35358.823205883804, 35390.29219415546, 35434.822825885705, 35559.644403555634, 35287.0, 35331.470027949086, 35689.82155089312, 36051.0, 36108.27783444264, 34781.26093695356, 35083.91283263861, 35384.46923296204, 35535.822320888234, 35900.28199436049, 29674.554881677224, 33423.498647519824, 33493.66506334906, 33845.323093538485, 33867.4919876207, 24960.500789983736, 25276.87361563254, 25511.0, 25516.744832551438, 25691.35771605725], "value": 16680.174107167317, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.33832342799553, 342.48285089514826, 346.6793216274946, 352.73721077797046, 379.0466975556476, 10984.121270298378, 15660.921695391535, 15723.842761572381, 17043.233054512544, 17258.395956141536, 16458.75311870323, 16515.917420412912, 17231.22459489323, 17346.132693365314, 17432.47702568922, 4793.664443488957, 4845.200541910598, 5004.899902001957, 5026.220935754964, 5051.3938327400765, 3541.8937431877084, 4944.159492886218, 5009.398872135349, 5019.924701129486, 5024.371953505812, 3736.5142531471006, 5689.630174038674, 6000.429959153874, 6033.456988871001, 6049.637021778685, 5076.822311219126, 5345.786168553253, 5990.221271234734, 5999.100134979744, 6005.519558435315, 5267.367915850123, 5269.7365131743645, 5271.65734227274, 5274.947250527484, 5275.28783614211, 11135.888641113566, 13277.668058298523, 20078.397648070437, 20143.0935607898, 20253.392398228076, 1687.7130887749113, 1780.777402824647, 1851.2039822876159, 1870.8971006594631, 1973.526353675122, 8303.543305118215, 8390.160983901622, 8732.650693972233, 8759.729839173348, 8908.064653211384, 1684.5199118251362, 1699.286299754094, 1714.4342367018853, 1719.5615118144792, 1740.0429763629952, 70174.0, 70631.0, 72175.99999999948, 72506.0, 73281.63359183223, 9779.364341317934, 9971.252156088178, 9978.850317245247, 10116.08955194036, 10198.235132365102, 13752.724945501033, 13757.724845503235, 13766.862331376558, 13783.310834458238, 13790.931045344909, 6420.903686444707, 6474.5791523550815, 6704.731810727516, 6715.1941766988275, 6785.694643741043, 5980.0, 6006.759729610767, 6148.200733904564, 6161.815145545641, 6164.291106522807, 4252.29837076885, 4256.425382573375, 4313.180495705868, 4337.934930976038, 4429.734215947054, 1684.7135986882015, 1782.9375971840923, 1788.4277031350102, 1800.0, 1843.907804609764, 766.938644908406, 804.9476784009021, 810.2424233341844, 849.3502470609989, 862.7454900804153, 16244.675106497803, 16602.853115534224, 16881.831181688027, 16966.830331696525, 17081.658366832595, 781.5427974634903, 802.9799255018671, 823.4473133286882, 849.668629234599, 922.4465320807597, 9273.0, 9510.837262749039, 9751.512424378752, 9806.0, 9892.851607225897, 403068.0, 410168.0, 410461.0, 416240.0, 424358.0, 78291.0, 79530.6023469879, 79797.0, 80820.19179808244, 81598.0, 864.5374724522351, 887.3433659092225, 888.8800011998427, 891.197921870309, 893.0489029183898, 14283.42866285337, 15183.848161518685, 15295.464658736892, 16451.506454806375, 16709.16454177262, 34678.82660586681, 35914.64085359113, 37154.8142259287, 38799.418008730456, 39465.60534394619, 49049.0, 51464.485355147466, 52432.737836310574, 52862.47137528729, 54516.45483545272, 6512.967435162984, 6673.332666733288, 6702.028205910213, 6902.24075351721, 6928.618925958919, 8692.91307086921, 11570.94214528922, 11647.359395233343, 11857.762844743229, 12407.813882791947, 8671.6097775599, 9744.707658769965, 9924.900750992687, 10041.297109202285, 10282.537285822305, 9718.75703107428, 9774.853377199206, 9946.850797238192, 10029.398236105857, 10223.284370094021, 9674.951625241829, 9718.319717619699, 9767.80464390694, 9956.751081222737, 10127.0, 6531.3468653134305, 6537.313582074004, 6690.264070952288, 6921.48088893325, 7439.442041846992, 6970.79087627372, 9272.443653380818, 9334.953325233331, 9385.953070234606, 9880.703578892644], "value": 7908.45445507247, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1199.4602428906983, 1298.9610311690649, 1340.5040135149993, 1361.8638136186376, 1626.1706529669864, 6815.148106486689, 7562.395008399329, 7855.646495907684, 7861.646225919833, 8087.474314169574, 7894.802629934255, 7960.1243863175005, 7980.960095199516, 7990.200979902007, 7993.3205677517335, 2137.732783402075, 2164.6211912915255, 2166.6100101981638, 2166.696662467256, 2167.295628920599, 2110.9867263713427, 2137.7434707835005, 2181.5418762059985, 2181.890905454729, 2184.4102092435114, 2130.509982703979, 2149.032935179168, 2156.3315372234656, 2159.125554150564, 2164.8051675349216, 2109.915603375871, 2125.341144245281, 2127.042830726164, 2129.307974908161, 2135.903884325209, 2050.8974551272377, 2093.0, 2097.328854766475, 2102.9158833646634, 2108.0, 7569.659365328518, 7814.296713295774, 7922.366210703188, 7930.801729956739, 8037.035555733291, 4353.1293741251875, 4478.216312145394, 4492.797824097891, 4496.2581174106235, 4532.297493888453, 2907.694692057346, 2957.053742802304, 2993.311538346171, 3020.426119037376, 3033.8634761435787, 956.2636769687302, 961.20700422152, 961.6345788600357, 963.5423173992367, 984.2027957354494, 10443.947780261125, 10456.268061235716, 10471.267011309212, 10519.0, 10543.683689489328, 3975.980120099381, 4026.6376026157786, 4094.7338423003007, 4174.269502836992, 4183.623473887304, 8366.37252206087, 8443.95778021106, 8466.407351485337, 8470.110638383005, 8484.109168537214, 4298.290782020994, 4299.355096735515, 4299.484061912528, 4311.0, 4337.631301339421, 3477.2871561329866, 3572.732045096628, 3900.64894159527, 4059.7564146151326, 4068.2473742357165, 3290.851911663981, 3345.8996230113135, 3455.6026057003764, 3458.7060099891287, 3464.082018265162, 951.1979055829604, 990.8315586350337, 997.2520609542843, 1004.7437903334603, 1013.2451323763843, 718.7340683946973, 749.2956620776453, 761.9771406857803, 763.9427042971688, 784.5096814490943, 4926.8768280793265, 4999.850004499871, 5029.0, 5058.494150584912, 5298.099323115002, 394.0994826820701, 395.70717668925363, 399.86204759357975, 401.12754758400365, 402.7442573965494, 3187.2031992002, 3509.191808894147, 3533.6819686228364, 3559.83980720863, 3574.6067932527403, 9659.130678238993, 10029.949850250701, 10036.146927511243, 10046.0, 10052.195824334036, 9932.851007234898, 9937.900620993698, 9944.900550994542, 9988.950055249677, 9990.250731195189, 7500.174980752113, 7587.317141457185, 7588.0, 7590.20302868213, 7608.391328693691, 4929.926051109164, 4945.876353091057, 5110.872228194325, 5174.8965020700125, 5350.759215835219, 8994.100589940954, 9063.63745450201, 9126.498042607454, 9146.771330716785, 9163.496007719372, 8944.463332200088, 9203.079692030744, 9246.167844893991, 9268.768280793034, 9415.199708024895, 6282.503019474259, 6345.619262844244, 6441.613503189824, 6462.450691691259, 6478.578892371981, 7245.818854528679, 7282.089738782653, 7298.854022919406, 7298.927010729824, 7301.926980730124, 6588.7035083422315, 6748.1227440434395, 6750.966245168743, 6786.626735529593, 6936.098307220029, 6605.1083103780375, 6621.9337806621315, 6646.767363142267, 6710.228323742636, 6812.897806533005, 6048.334683184934, 6071.787487437919, 6111.633302001894, 6115.327313995546, 6116.571839971337, 4455.5098939117315, 4496.797644105957, 4584.0, 4595.425571803525, 4645.883852903705, 4433.268510695699, 4467.553244675506, 4595.126925884071, 4597.747123908217, 4701.106789709944], "value": 3964.775524011507, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.4497812018182, 276.5285188753176, 278.9358596952624, 279.04705430953294, 283.4288907850681, 4636.211843986522, 4771.952280477194, 4774.427068751755, 4827.589654879335, 4834.927476087862, 4622.930656040162, 4728.716277023381, 4732.952670473294, 4739.028499157673, 4763.523647635235, 3542.6988705960025, 3624.3113808376456, 3649.4708267301226, 3718.72109591781, 3739.383001804699, 3745.4381842723674, 3748.3815170496837, 3754.080250338657, 3755.906102347436, 3760.4735337052834, 3714.554253489585, 3724.16206353571, 3729.179580492287, 3737.588865224823, 3738.252349530091, 3727.1800203955086, 3728.776273423576, 3742.419924911643, 3747.2505498900327, 3748.6063963283873, 3183.283761153745, 3243.8864639737494, 3255.1048461672995, 3258.3809076275434, 3260.608726952757, 4604.516525764813, 4618.792154353062, 4625.514320996281, 4631.235846085392, 4656.138614356321, 4076.408920706511, 4230.428892099558, 4233.364995250707, 4237.470316210473, 4237.9022383920155, 4916.975415122937, 4953.455119936804, 4979.975100124512, 4980.975095124538, 4997.30037794709, 3516.033090899997, 3522.5949015862993, 3528.523649307336, 3545.663161999606, 3569.6430356964356, 5193.714345710984, 5222.268882356473, 5225.634205605609, 5227.529522343008, 5232.136697444917, 3435.783919372041, 3735.7945313007504, 3737.8507358269103, 3761.8683346082757, 3766.6986641068647, 3778.4332350147147, 3794.335991201584, 3810.447485114643, 3810.48558444612, 3812.17015832402, 4264.0, 4288.185244803477, 4294.570542945743, 4300.720453170597, 4302.139572085533, 4728.0, 4783.330333753277, 4873.853784386474, 4997.90004199914, 5001.89996200074, 1305.7649623067748, 1318.228836130855, 1318.8944884409225, 1319.7198717244257, 1320.2540564580981, 811.7848770075934, 854.495847449995, 858.1932982995963, 861.2679222660688, 865.5455885660078, 3960.5643379228263, 3969.2656858481273, 3975.6223158799867, 3983.342748446532, 3990.4612877261197, 4517.537941821715, 4531.320301954733, 4535.63714902807, 4538.298138198175, 4538.88941642138, 3958.030282580786, 3967.3850553164307, 3977.2642061218767, 3994.460747799068, 4007.278689835799, 2268.6256767633486, 2411.963820542693, 2415.9154429595233, 2424.757524247561, 2454.9509009819703, 4362.451329777947, 4373.2565463871215, 4377.9781101094295, 4379.255526560429, 4384.69307148503, 4675.2052151134385, 4679.836205732783, 4690.296455531698, 4692.41344831896, 4693.272542755878, 4159.438475805728, 4338.139056239886, 4370.431843860278, 4370.912581748411, 4378.824847006148, 4561.035327037453, 4563.330451685668, 4576.656750743641, 4580.244259697112, 4580.381648477412, 4477.776111194493, 4514.171760436897, 4515.34527493505, 4528.728276303301, 4554.385158003627, 4140.896477588084, 4315.697901147015, 4321.978390108029, 4333.783310834383, 4338.913221735611, 3381.9492707608915, 3395.966040339565, 3402.8468718907216, 3429.5713035870517, 3437.5249820129466, 4532.297493888453, 4566.337881007169, 4589.2657174851865, 4846.975765121152, 5002.774875130701, 4864.294677271847, 4969.279454479152, 5013.423456302572, 5021.4225364083595, 5031.949680503149, 4960.23116416956, 5027.522385373383, 5034.798608055637, 5035.395752509722, 5048.343715316985, 4723.244280915037, 4887.706737595613, 4895.360054381822, 4951.826686066114, 4953.702777833341, 4995.850124496271, 5018.397792264952, 5056.519630635084, 5062.670926389773, 5064.265681476239, 4937.284093806306, 4944.8847226247235, 4946.3817022872145, 4958.785097651099, 4959.677620954626], "value": 3634.2525233676242, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.2956216043466, 580.9680467574287, 584.2930054633892, 625.7425742574258, 653.5555822041011, 11830.940845295772, 12402.255864648127, 12422.93788531056, 12439.68900777481, 12440.564580239705, 11970.28178309302, 12028.218165819215, 12032.578859739911, 12034.879651203486, 12036.759264814698, 584.2200662116059, 600.7777122464694, 716.57721944053, 736.1166600079926, 739.0355585960319, 713.5468977199464, 733.3986131372277, 737.2443245673201, 740.2634378793108, 740.6518936100051, 781.8006408365859, 784.0904550721165, 784.2353705137502, 787.2049230277404, 788.7830846517196, 607.1469585232755, 626.4862812493757, 778.2101167315179, 778.5562229529171, 778.8208711996273, 547.9561635069185, 562.3420597900466, 568.9943100568983, 569.4106599669323, 569.422036632818, 12487.06347023977, 12521.874781252161, 12522.123451358408, 12522.248665080124, 12525.686857828536, 11583.305001699924, 11838.171328007043, 11869.46587403569, 12032.186746867887, 13055.54305599309, 9445.149936505679, 9584.185344245747, 11683.766324673545, 11831.290122592673, 11871.169018168732, 3945.3490174121234, 4049.190161967618, 4077.755334679899, 4089.8159582818844, 4235.0682849773, 58722.0, 58805.70597146987, 59436.70281648564, 59887.40112598862, 60642.69678651623, 9995.200383969264, 10094.747631309128, 10132.645357412455, 10160.441175735257, 10324.43215623148, 17663.558411039827, 17919.820801791815, 18088.276468941094, 18132.818671813377, 18163.0, 6456.0961465394885, 6524.282328943812, 6526.836829079216, 6624.072629831828, 6822.590644561243, 3578.3380074686265, 3652.2878038782183, 3668.174660701321, 3718.5537735471376, 3789.6778773803985, 2591.663083799132, 2720.8911643534434, 2721.251655794633, 2722.605222242764, 2723.319170207448, 459.8574441922948, 460.07984031936127, 465.12556393979776, 468.68129671822805, 475.69793181330107, 428.12662169174536, 433.4863187123269, 441.1441802902359, 453.0825079214551, 472.0605994071841, 6688.428219368445, 6701.463882889358, 7024.157101147793, 7046.647667616701, 7195.496315257987, 456.06051533840395, 460.19006548474925, 464.3615029334665, 483.8935434204469, 504.6591563847354, 3283.7044665979697, 3360.630330663625, 3476.1228936374073, 3585.8924232273075, 3823.885283441501, 98991.0, 100159.0, 101193.49403253084, 102124.0, 104765.47617261866, 87760.0, 89530.55234723784, 91036.54481727681, 93221.5338923301, 93465.0, 49130.50869491331, 49137.754311228215, 49154.75422622936, 52268.0, 73345.63327183436, 6769.796906092825, 6800.761973330909, 6912.0, 7003.642213706416, 7014.68433920485, 47617.52382476131, 48136.75931620319, 48731.0, 48775.5122448771, 50334.244986324506, 59626.403735962085, 68675.0, 70163.0, 72987.63506182435, 73108.0, 8324.583770811556, 8376.87434688468, 8455.112213217524, 8693.652253909773, 8820.0, 8612.48325100496, 8770.342224333073, 8869.68956086534, 8965.282777377664, 9043.864342034743, 8676.379971774244, 8857.601407936787, 8883.600237989176, 9097.681581144629, 9140.725778226664, 8534.615942282488, 8554.358423118167, 8555.101714319906, 8689.0, 9063.546822658973, 7689.461737678309, 7733.304002639789, 7764.456488045783, 7796.649150788115, 7886.960565197367, 7463.701451941862, 7623.16145224036, 7631.427642926692, 7762.5730584818375, 7960.442769006114, 7225.747098851515, 7568.5458872467825, 7711.768646940601, 7723.961380193063, 8058.798530036796], "value": 5880.642773248306, "unit": "score"}], "name": "dromaeo_css", "value": 6098.525187717947, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 115.0, 115.0, 117.0, 117.0, 115.0, 115.0, 115.0, 116.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 125.0, 125.0, 161.0, 126.0, 125.0, 124.0, 125.0, 164.0, 127.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 162.0, 163.0, 164.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 114.0, 77.0, 79.0, 79.0, 76.0, 107.0, 77.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 111.0, 85.0, 83.0, 84.0, 112.0, 84.0, 84.0, 112.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 148.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 65.0, 67.0, 66.0, 65.0, 67.0, 67.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 81.0, 83.0, 83.0, 89.0, 81.0, 81.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 148.0, 150.0, 138.0, 132.0, 133.0, 132.0, 135.0, 167.0, 135.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 176.0, 175.0, 177.0, 181.0, 174.0, 176.0, 177.0, 176.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 70.0, 69.0, 69.0, 69.0, 69.0, 67.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1547.0000000000002, "alertThreshold": 2.0}]} 09:48:12 INFO - Return code: 0 09:48:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:12 INFO - # TBPL SUCCESS # 09:48:12 INFO - Running post-action listener: _resource_record_post_action 09:48:12 INFO - [mozharness: 2016-09-17 16:48:12.863881Z] Finished run-tests step (success) 09:48:12 INFO - Running post-run listener: _resource_record_post_run 09:48:12 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 394186752; Read time: 16; Write time: 273704 09:48:12 INFO - TinderboxPrint: CPU usage
13.1% 09:48:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:48:12 INFO - TinderboxPrint: I/O write bytes / time
394,186,752 / 273,704 09:48:12 INFO - TinderboxPrint: CPU idle
8,329.2 (86.8%) 09:48:12 INFO - TinderboxPrint: CPU user
1,208.6 (12.6%) 09:48:12 INFO - TinderboxPrint: Swap in / out
0 / 0 09:48:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99205120; Read time: 0; Write time: 68028 09:48:12 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 294981632; Read time: 0; Write time: 205676 09:48:13 INFO - Running post-run listener: _upload_blobber_files 09:48:13 INFO - Blob upload gear active. 09:48:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:13 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 09:48:13 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'] 09:48:13 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 09:48:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:17 INFO - (blobuploader) - INFO - Done attempting. 09:48:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:48:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:48:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:17 INFO - (blobuploader) - INFO - Done attempting. 09:48:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:48:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:48:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:18 INFO - (blobuploader) - INFO - Done attempting. 09:48:18 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:18 INFO - Return code: 0 09:48:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bb893ced5c119bd2283a1edcf44abc8f288326635b3491b6104ce2dd031c8a6e7cc35a37da7e39e5e7d928bdac4babe609b069b0bcff4e826f8f6577e397f656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6df17b99f89cc545a0bb4748f2a1561d3faa8e4ab2804706ec5ae7c749c50b54b56ba09cb21f3fff8dccd1973a3ca120bedea694dd43545a93a77babb8f0071e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b47ba603a0e434304a0bda676cb3b8f70b27e646b8ab41bda10fc3d404fdb35aedf2d4775aeb075222bbac6635d1a59510580ba7294351d5ecdd74398319341f"} 09:48:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:18 INFO - Contents: 09:48:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bb893ced5c119bd2283a1edcf44abc8f288326635b3491b6104ce2dd031c8a6e7cc35a37da7e39e5e7d928bdac4babe609b069b0bcff4e826f8f6577e397f656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6df17b99f89cc545a0bb4748f2a1561d3faa8e4ab2804706ec5ae7c749c50b54b56ba09cb21f3fff8dccd1973a3ca120bedea694dd43545a93a77babb8f0071e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b47ba603a0e434304a0bda676cb3b8f70b27e646b8ab41bda10fc3d404fdb35aedf2d4775aeb075222bbac6635d1a59510580ba7294351d5ecdd74398319341f"} 09:48:18 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:18 INFO - Copying logs to upload dir... 09:48:18 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.421154 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-17 09:48:18.689579) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:18.693123) ========= 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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 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/bb893ced5c119bd2283a1edcf44abc8f288326635b3491b6104ce2dd031c8a6e7cc35a37da7e39e5e7d928bdac4babe609b069b0bcff4e826f8f6577e397f656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6df17b99f89cc545a0bb4748f2a1561d3faa8e4ab2804706ec5ae7c749c50b54b56ba09cb21f3fff8dccd1973a3ca120bedea694dd43545a93a77babb8f0071e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b47ba603a0e434304a0bda676cb3b8f70b27e646b8ab41bda10fc3d404fdb35aedf2d4775aeb075222bbac6635d1a59510580ba7294351d5ecdd74398319341f"} build_url:https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/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/bb893ced5c119bd2283a1edcf44abc8f288326635b3491b6104ce2dd031c8a6e7cc35a37da7e39e5e7d928bdac4babe609b069b0bcff4e826f8f6577e397f656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6df17b99f89cc545a0bb4748f2a1561d3faa8e4ab2804706ec5ae7c749c50b54b56ba09cb21f3fff8dccd1973a3ca120bedea694dd43545a93a77babb8f0071e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b47ba603a0e434304a0bda676cb3b8f70b27e646b8ab41bda10fc3d404fdb35aedf2d4775aeb075222bbac6635d1a59510580ba7294351d5ecdd74398319341f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:18.720712) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:18.721302) ========= 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=db0483c52d987ecb441a263b000001ad-1474129652.612203-340822807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:18.901646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 09:48:18.902027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 09:48:18.902515) ========= ========= Total master_lag: 0.39 =========