builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1474125094.49 results: success (0) buildid: 20160917075419 builduid: 3b5de178357749099fe50aaa1f6817d7 revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.488584) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.489013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.489285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.503683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.503944) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:11:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.1M=0s 2016-09-17 08:11:35 (25.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113392 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.630952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.631260) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018459 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.680346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:34.680686) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:11:35,117 truncating revision to first 12 chars 2016-09-17 08:11:35,117 Setting DEBUG logging. 2016-09-17 08:11:35,117 attempt 1/10 2016-09-17 08:11:35,117 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 08:11:35,406 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631036 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:35.324956) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:35.325271) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:35.325645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:35.325971) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:35.335497) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-17 08:11:35.335878) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:11:35 INFO - MultiFileLogger online at 20160917 08:11:35 in /builds/slave/test 08:11:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:11:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:35 INFO - {'append_to_log': False, 08:11:35 INFO - 'base_work_dir': '/builds/slave/test', 08:11:35 INFO - 'blob_upload_branch': 'Firefox', 08:11:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:35 INFO - 'branch': 'Firefox', 08:11:35 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:35 INFO - 'config_files': ('talos/linux_config.py',), 08:11:35 INFO - 'default_actions': ('clobber', 08:11:35 INFO - 'read-buildbot-config', 08:11:35 INFO - 'download-and-extract', 08:11:35 INFO - 'populate-webroot', 08:11:35 INFO - 'create-virtualenv', 08:11:35 INFO - 'install', 08:11:35 INFO - 'run-tests'), 08:11:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:35 INFO - 'download_minidump_stackwalk': True, 08:11:35 INFO - 'download_symbols': 'ondemand', 08:11:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:35 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:35 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:35 INFO - 'installer_path': 'installer.exe', 08:11:35 INFO - 'log_level': 'info', 08:11:35 INFO - 'log_name': 'talos', 08:11:35 INFO - 'log_to_console': True, 08:11:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:11:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:11:35 INFO - 'opt_config_files': (), 08:11:35 INFO - 'pip_index': False, 08:11:35 INFO - 'sps_profile': False, 08:11:35 INFO - 'sps_profile_interval': 0, 08:11:35 INFO - 'suite': 'dromaeojs', 08:11:35 INFO - 'system_bits': '32', 08:11:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:35 INFO - 'title': 'talos-linux64-ix-022', 08:11:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:35 INFO - 'use_talos_json': True, 08:11:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:35 INFO - 'work_dir': 'build'} 08:11:35 INFO - [mozharness: 2016-09-17 15:11:35.874806Z] Running clobber step. 08:11:35 INFO - Running pre-action listener: _resource_record_pre_action 08:11:35 INFO - Running main action method: clobber 08:11:35 INFO - rmtree: /builds/slave/test/build 08:11:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:36 INFO - Running post-action listener: _resource_record_post_action 08:11:36 INFO - [mozharness: 2016-09-17 15:11:36.332447Z] Finished clobber step (success) 08:11:36 INFO - [mozharness: 2016-09-17 15:11:36.332548Z] Running read-buildbot-config step. 08:11:36 INFO - Running pre-action listener: _resource_record_pre_action 08:11:36 INFO - Running main action method: read_buildbot_config 08:11:36 INFO - Using buildbot properties: 08:11:36 INFO - { 08:11:36 INFO - "project": "", 08:11:36 INFO - "product": "firefox", 08:11:36 INFO - "script_repo_revision": "production", 08:11:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:11:36 INFO - "repository": "", 08:11:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:11:36 INFO - "buildid": "20160917075419", 08:11:36 INFO - "stage_platform": "linux64-pgo", 08:11:36 INFO - "basedir": "/builds/slave/test", 08:11:36 INFO - "buildnumber": 578, 08:11:36 INFO - "platform": "ubuntu64_hw", 08:11:36 INFO - "mozci_request": { 08:11:36 INFO - "priority": -1, 08:11:36 INFO - "type": "trigger_all_talos_jobs", 08:11:36 INFO - "times": 6 08:11:36 INFO - }, 08:11:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:11:36 INFO - "slavebuilddir": "test", 08:11:36 INFO - "branch": "mozilla-central", 08:11:36 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 08:11:36 INFO - "slavename": "talos-linux64-ix-022", 08:11:36 INFO - "builduid": "3b5de178357749099fe50aaa1f6817d7", 08:11:36 INFO - "repo_path": "mozilla-central" 08:11:36 INFO - } 08:11:36 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:11:36 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 08:11:36 INFO - Running post-action listener: _resource_record_post_action 08:11:36 INFO - [mozharness: 2016-09-17 15:11:36.335355Z] Finished read-buildbot-config step (success) 08:11:36 INFO - [mozharness: 2016-09-17 15:11:36.335444Z] Running download-and-extract step. 08:11:36 INFO - Running pre-action listener: _resource_record_pre_action 08:11:36 INFO - Running main action method: download_and_extract 08:11:36 INFO - mkdir: /builds/slave/test/build/tests 08:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:36 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:36 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:36 INFO - Downloaded 1361 bytes. 08:11:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:36 INFO - Using the following test package requirements: 08:11:36 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:11:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:11:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'jsshell-linux-x86_64.zip'], 08:11:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:11:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:11:36 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:11:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:11:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:11:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:11:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:36 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:36 INFO - Expected file size: 21936347 08:11: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 08:11:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:40 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:40 INFO - Expected file size: 21936347 08:11:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:11:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:11:45 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:11:45 INFO - Expected file size: 14414048 08:11: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')]} 08:11:46 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:46 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:46 INFO - Downloaded 59535581 bytes. 08:11:46 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:46 INFO - mkdir: /builds/slave/test/properties 08:11:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:46 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:46 INFO - Contents: 08:11:46 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:47 INFO - Running post-action listener: _resource_record_post_action 08:11:47 INFO - Running post-action listener: set_extra_try_arguments 08:11:47 INFO - [mozharness: 2016-09-17 15:11:47.026702Z] Finished download-and-extract step (success) 08:11:47 INFO - [mozharness: 2016-09-17 15:11:47.026930Z] Running populate-webroot step. 08:11:47 INFO - Running pre-action listener: _resource_record_pre_action 08:11:47 INFO - Running main action method: populate_webroot 08:11:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:47 INFO - '--tppagecycles', 08:11:47 INFO - '7'], 08:11:47 INFO - 'tests': ['tsvgm']}}, 08:11:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['damp', 'tps']}, 08:11:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'tests': ['damp', 'tps']}, 08:11:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['dromaeo_dom']}, 08:11:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['basic_compositor_video']}, 08:11:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['a11yr', 08:11:47 INFO - 'ts_paint', 08:11:47 INFO - 'tpaint', 08:11:47 INFO - 'sessionrestore', 08:11:47 INFO - 'sessionrestore_no_auto_restore', 08:11:47 INFO - 'tabpaint']}, 08:11:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['tsvgx', 08:11:47 INFO - 'tsvgr_opacity', 08:11:47 INFO - 'tart', 08:11:47 INFO - 'tscrollx', 08:11:47 INFO - 'cart']}, 08:11:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:47 INFO - 'tsvgr_opacity', 08:11:47 INFO - 'tart', 08:11:47 INFO - 'tscrollx', 08:11:47 INFO - 'cart']}, 08:11:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'talos_options': ['--disable-e10s'], 08:11:47 INFO - 'tests': ['tp5o']}, 08:11:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'tests': ['tp5o']}, 08:11:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'talos_options': ['--disable-e10s', 08:11:47 INFO - '--xperf_path', 08:11:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:47 INFO - 'tests': ['tp5n']}, 08:11:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:47 INFO - 'talos_options': ['--xperf_path', 08:11:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:47 INFO - 'tests': ['tp5n']}}, 08:11:47 INFO - 'talos.zip': {'path': '', 08:11:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:47 INFO - Running post-action listener: _resource_record_post_action 08:11:47 INFO - [mozharness: 2016-09-17 15:11:47.044425Z] Finished populate-webroot step (success) 08:11:47 INFO - [mozharness: 2016-09-17 15:11:47.044514Z] Running create-virtualenv step. 08:11:47 INFO - Running pre-action listener: _resource_record_pre_action 08:11:47 INFO - Running main action method: create_virtualenv 08:11:47 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:47 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 08:11:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:47 INFO - Using real prefix '/usr' 08:11:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:47 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:49 INFO - Installing pip.................done. 08:11:49 INFO - Return code: 0 08:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:49 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')]} 08:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:49 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')]} 08:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:49 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11:49 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 08:11:49 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 08:11:49 INFO - Using env: {'DISPLAY': ':0', 08:11:49 INFO - 'HOME': '/home/cltbld', 08:11:49 INFO - 'LANG': 'en_US.UTF-8', 08:11:49 INFO - 'LANGUAGE': 'en_US:en', 08:11:49 INFO - 'LOGNAME': 'cltbld', 08:11:49 INFO - 'MAIL': '/var/mail/cltbld', 08:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:49 INFO - 'MOZ_NO_REMOTE': '1', 08:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:49 INFO - 'NO_EM_RESTART': '1', 08:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:49 INFO - 'PWD': '/builds/slave/test', 08:11:49 INFO - 'SHELL': '/bin/bash', 08:11:49 INFO - 'SHLVL': '1', 08:11:49 INFO - 'TERM': 'linux', 08:11:49 INFO - 'TMOUT': '86400', 08:11:49 INFO - 'USER': 'cltbld', 08:11:49 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:49 INFO - '_': '/tools/buildbot/bin/python'} 08:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:49 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 08:11:49 INFO - Cleaning up... 08:11:49 INFO - Return code: 0 08:11:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:49 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')]} 08:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:49 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')]} 08:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:49 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11:49 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 08:11:49 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 08:11:49 INFO - Using env: {'DISPLAY': ':0', 08:11:49 INFO - 'HOME': '/home/cltbld', 08:11:49 INFO - 'LANG': 'en_US.UTF-8', 08:11:49 INFO - 'LANGUAGE': 'en_US:en', 08:11:49 INFO - 'LOGNAME': 'cltbld', 08:11:49 INFO - 'MAIL': '/var/mail/cltbld', 08:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:49 INFO - 'MOZ_NO_REMOTE': '1', 08:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:49 INFO - 'NO_EM_RESTART': '1', 08:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:49 INFO - 'PWD': '/builds/slave/test', 08:11:49 INFO - 'SHELL': '/bin/bash', 08:11:49 INFO - 'SHLVL': '1', 08:11:49 INFO - 'TERM': 'linux', 08:11:49 INFO - 'TMOUT': '86400', 08:11:49 INFO - 'USER': 'cltbld', 08:11:49 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:49 INFO - '_': '/tools/buildbot/bin/python'} 08:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:50 INFO - Downloading/unpacking psutil>=3.1.1 08:11:50 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 08:11:50 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 08:11:50 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 08:11:50 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 08:11:50 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 08:11:50 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 08:11:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:52 INFO - Installing collected packages: psutil 08:11:52 INFO - Running setup.py install for psutil 08:11:52 INFO - building 'psutil._psutil_linux' extension 08:11:52 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 08:11:52 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 08:11:52 INFO - building 'psutil._psutil_posix' extension 08:11:52 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 08:11:52 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 08:11:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:52 INFO - Successfully installed psutil 08:11:52 INFO - Cleaning up... 08:11:52 INFO - Return code: 0 08:11:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11:52 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')]} 08:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:52 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')]} 08:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:52 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11:52 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 08:11:52 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 08:11:52 INFO - Using env: {'DISPLAY': ':0', 08:11:52 INFO - 'HOME': '/home/cltbld', 08:11:52 INFO - 'LANG': 'en_US.UTF-8', 08:11:52 INFO - 'LANGUAGE': 'en_US:en', 08:11:52 INFO - 'LOGNAME': 'cltbld', 08:11:52 INFO - 'MAIL': '/var/mail/cltbld', 08:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:52 INFO - 'MOZ_NO_REMOTE': '1', 08:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:52 INFO - 'NO_EM_RESTART': '1', 08:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:52 INFO - 'PWD': '/builds/slave/test', 08:11:52 INFO - 'SHELL': '/bin/bash', 08:11:52 INFO - 'SHLVL': '1', 08:11:52 INFO - 'TERM': 'linux', 08:11:52 INFO - 'TMOUT': '86400', 08:11:52 INFO - 'USER': 'cltbld', 08:11:52 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:52 INFO - '_': '/tools/buildbot/bin/python'} 08:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:11:54 INFO - Installing collected packages: mozsystemmonitor 08:11:54 INFO - Running setup.py install for mozsystemmonitor 08:11:54 INFO - Successfully installed mozsystemmonitor 08:11:54 INFO - Cleaning up... 08:11:54 INFO - Return code: 0 08:11:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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', '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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:11: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 jsonschema==2.5.1 08:11:54 INFO - Using env: {'DISPLAY': ':0', 08:11:54 INFO - 'HOME': '/home/cltbld', 08:11:54 INFO - 'LANG': 'en_US.UTF-8', 08:11:54 INFO - 'LANGUAGE': 'en_US:en', 08:11:54 INFO - 'LOGNAME': 'cltbld', 08:11:54 INFO - 'MAIL': '/var/mail/cltbld', 08:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:54 INFO - 'MOZ_NO_REMOTE': '1', 08:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:54 INFO - 'NO_EM_RESTART': '1', 08:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:54 INFO - 'PWD': '/builds/slave/test', 08:11:54 INFO - 'SHELL': '/bin/bash', 08:11:54 INFO - 'SHLVL': '1', 08:11:54 INFO - 'TERM': 'linux', 08:11:54 INFO - 'TMOUT': '86400', 08:11:54 INFO - 'USER': 'cltbld', 08:11:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:54 INFO - '_': '/tools/buildbot/bin/python'} 08:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:55 INFO - Downloading/unpacking jsonschema==2.5.1 08:11:55 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 08:11:55 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 08:11:55 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 08:11:55 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 08:11:55 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 08:11:55 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 08:11:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:57 INFO - warning: no files found matching '*.txt' 08:11:57 INFO - no previously-included directories found matching 'build' 08:11:57 INFO - no previously-included directories found matching 'dist' 08:11:57 INFO - no previously-included directories found matching '.git*' 08:11:57 INFO - Installing collected packages: jsonschema, functools32 08:11:57 INFO - Running setup.py install for functools32 08:11:57 INFO - warning: no files found matching '*.txt' 08:11:57 INFO - no previously-included directories found matching 'build' 08:11:57 INFO - no previously-included directories found matching 'dist' 08:11:57 INFO - no previously-included directories found matching '.git*' 08:11:57 INFO - Successfully installed jsonschema functools32 08:11:57 INFO - Cleaning up... 08:11:57 INFO - Return code: 0 08:11:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:57 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11:57 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 08:11:57 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 08:11:57 INFO - Using env: {'DISPLAY': ':0', 08:11:57 INFO - 'HOME': '/home/cltbld', 08:11:57 INFO - 'LANG': 'en_US.UTF-8', 08:11:57 INFO - 'LANGUAGE': 'en_US:en', 08:11:57 INFO - 'LOGNAME': 'cltbld', 08:11:57 INFO - 'MAIL': '/var/mail/cltbld', 08:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:57 INFO - 'MOZ_NO_REMOTE': '1', 08:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:57 INFO - 'NO_EM_RESTART': '1', 08:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:57 INFO - 'PWD': '/builds/slave/test', 08:11:57 INFO - 'SHELL': '/bin/bash', 08:11:57 INFO - 'SHLVL': '1', 08:11:57 INFO - 'TERM': 'linux', 08:11:57 INFO - 'TMOUT': '86400', 08:11:57 INFO - 'USER': 'cltbld', 08:11:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:57 INFO - '_': '/tools/buildbot/bin/python'} 08:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:11:57 INFO - Cleaning up... 08:11:57 INFO - Return code: 0 08:11:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:57 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:11:57 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 08:11:57 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 08:11:57 INFO - Using env: {'DISPLAY': ':0', 08:11:57 INFO - 'HOME': '/home/cltbld', 08:11:57 INFO - 'LANG': 'en_US.UTF-8', 08:11:57 INFO - 'LANGUAGE': 'en_US:en', 08:11:57 INFO - 'LOGNAME': 'cltbld', 08:11:57 INFO - 'MAIL': '/var/mail/cltbld', 08:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:57 INFO - 'MOZ_NO_REMOTE': '1', 08:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:57 INFO - 'NO_EM_RESTART': '1', 08:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:57 INFO - 'PWD': '/builds/slave/test', 08:11:57 INFO - 'SHELL': '/bin/bash', 08:11:57 INFO - 'SHLVL': '1', 08:11:57 INFO - 'TERM': 'linux', 08:11:57 INFO - 'TMOUT': '86400', 08:11:57 INFO - 'USER': 'cltbld', 08:11:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:57 INFO - '_': '/tools/buildbot/bin/python'} 08:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:57 INFO - Downloading/unpacking blobuploader==1.2.4 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:59 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:12:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:00 INFO - Downloading docopt-0.6.1.tar.gz 08:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:12:00 INFO - Installing collected packages: blobuploader, requests, docopt 08:12:00 INFO - Running setup.py install for blobuploader 08:12:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:12:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:12:00 INFO - Running setup.py install for requests 08:12:01 INFO - Running setup.py install for docopt 08:12:01 INFO - Successfully installed blobuploader requests docopt 08:12:01 INFO - Cleaning up... 08:12:01 INFO - Return code: 0 08:12:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:01 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')]} 08:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:01 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')]} 08:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:01 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:12:01 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 08:12:01 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 08:12:01 INFO - Using env: {'DISPLAY': ':0', 08:12:01 INFO - 'HOME': '/home/cltbld', 08:12:01 INFO - 'LANG': 'en_US.UTF-8', 08:12:01 INFO - 'LANGUAGE': 'en_US:en', 08:12:01 INFO - 'LOGNAME': 'cltbld', 08:12:01 INFO - 'MAIL': '/var/mail/cltbld', 08:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:01 INFO - 'MOZ_NO_REMOTE': '1', 08:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:01 INFO - 'NO_EM_RESTART': '1', 08:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:01 INFO - 'PWD': '/builds/slave/test', 08:12:01 INFO - 'SHELL': '/bin/bash', 08:12:01 INFO - 'SHLVL': '1', 08:12:01 INFO - 'TERM': 'linux', 08:12:01 INFO - 'TMOUT': '86400', 08:12:01 INFO - 'USER': 'cltbld', 08:12:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:01 INFO - '_': '/tools/buildbot/bin/python'} 08:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:01 INFO - Running setup.py (path:/tmp/pip-16C8c4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:01 INFO - Running setup.py (path:/tmp/pip-5uELlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:01 INFO - Running setup.py (path:/tmp/pip-VFrc7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:01 INFO - Running setup.py (path:/tmp/pip-ASw6iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:01 INFO - Running setup.py (path:/tmp/pip-hi2b5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:01 INFO - Running setup.py (path:/tmp/pip-zaxrCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:01 INFO - Running setup.py (path:/tmp/pip-UBy9AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:01 INFO - Running setup.py (path:/tmp/pip-jqaPGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:01 INFO - Running setup.py (path:/tmp/pip-OHtE82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:02 INFO - Running setup.py (path:/tmp/pip-XPZGeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:02 INFO - Running setup.py (path:/tmp/pip-WhZNjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:02 INFO - Running setup.py (path:/tmp/pip-hoVhAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:02 INFO - Running setup.py (path:/tmp/pip-BNvH7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:02 INFO - Running setup.py (path:/tmp/pip-xjdlNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:02 INFO - Running setup.py (path:/tmp/pip-2NSylu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:02 INFO - Running setup.py (path:/tmp/pip-1BSxnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:02 INFO - Running setup.py (path:/tmp/pip-Ey9FBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:12:02 INFO - Running setup.py install for manifestparser 08:12:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:12:02 INFO - Running setup.py install for mozcrash 08:12:02 INFO - Running setup.py install for mozdebug 08:12:02 INFO - Running setup.py install for mozdevice 08:12:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozfile 08:12:03 INFO - Running setup.py install for mozhttpd 08:12:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozinfo 08:12:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozInstall 08:12:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozleak 08:12:03 INFO - Running setup.py install for mozlog 08:12:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for moznetwork 08:12:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozprocess 08:12:03 INFO - Running setup.py install for mozprofile 08:12:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:12:03 INFO - Running setup.py install for mozrunner 08:12:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:12:04 INFO - Running setup.py install for mozscreenshot 08:12:04 INFO - Running setup.py install for moztest 08:12:04 INFO - Running setup.py install for mozversion 08:12:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:12:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:12:04 INFO - Cleaning up... 08:12:04 INFO - Return code: 0 08:12:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:04 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')]} 08:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:04 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')]} 08:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:04 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:12:04 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 08:12:04 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 08:12:04 INFO - Using env: {'DISPLAY': ':0', 08:12:04 INFO - 'HOME': '/home/cltbld', 08:12:04 INFO - 'LANG': 'en_US.UTF-8', 08:12:04 INFO - 'LANGUAGE': 'en_US:en', 08:12:04 INFO - 'LOGNAME': 'cltbld', 08:12:04 INFO - 'MAIL': '/var/mail/cltbld', 08:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:04 INFO - 'MOZ_NO_REMOTE': '1', 08:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:04 INFO - 'NO_EM_RESTART': '1', 08:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:04 INFO - 'PWD': '/builds/slave/test', 08:12:04 INFO - 'SHELL': '/bin/bash', 08:12:04 INFO - 'SHLVL': '1', 08:12:04 INFO - 'TERM': 'linux', 08:12:04 INFO - 'TMOUT': '86400', 08:12:04 INFO - 'USER': 'cltbld', 08:12:04 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:04 INFO - '_': '/tools/buildbot/bin/python'} 08:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:04 INFO - Running setup.py (path:/tmp/pip-7W0TIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:04 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)) 08:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:04 INFO - Running setup.py (path:/tmp/pip-RFEo4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:04 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)) 08:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:04 INFO - Running setup.py (path:/tmp/pip-RW8IhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:04 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)) 08:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:04 INFO - Running setup.py (path:/tmp/pip-uAGP5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:04 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)) 08:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:04 INFO - Running setup.py (path:/tmp/pip-wq11U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:05 INFO - Running setup.py (path:/tmp/pip-aF3wOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:05 INFO - Running setup.py (path:/tmp/pip-0xwwln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:05 INFO - Running setup.py (path:/tmp/pip-1ZT4Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:05 INFO - Running setup.py (path:/tmp/pip-KzVvsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:05 INFO - Running setup.py (path:/tmp/pip-0kLp2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:05 INFO - Running setup.py (path:/tmp/pip-8ARRIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:05 INFO - Running setup.py (path:/tmp/pip-HWi_RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:05 INFO - Running setup.py (path:/tmp/pip-layofX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:05 INFO - Running setup.py (path:/tmp/pip-sJ1aKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:05 INFO - Running setup.py (path:/tmp/pip-98glCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:05 INFO - Running setup.py (path:/tmp/pip-HvAO4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:05 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)) 08:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:05 INFO - Running setup.py (path:/tmp/pip-z65gq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:05 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)) 08:12:05 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)) 08:12:05 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)) 08:12:05 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)) 08:12:05 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)) 08:12:05 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)) 08:12:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:05 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 08:12:05 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 08:12:05 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 08:12:05 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 08:12:05 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 08:12:05 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 08:12:07 INFO - Downloading blessings-1.6.tar.gz 08:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:12:07 INFO - Installing collected packages: blessings 08:12:07 INFO - Running setup.py install for blessings 08:12:07 INFO - Successfully installed blessings 08:12:07 INFO - Cleaning up... 08:12:08 INFO - Return code: 0 08:12:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:08 INFO - Reading from file tmpfile_stdout 08:12:08 INFO - Current package versions: 08:12:08 INFO - argparse == 1.2.1 08:12:08 INFO - blessings == 1.6 08:12:08 INFO - blobuploader == 1.2.4 08:12:08 INFO - docopt == 0.6.1 08:12:08 INFO - functools32 == 3.2.3-2 08:12:08 INFO - jsonschema == 2.5.1 08:12:08 INFO - manifestparser == 1.1 08:12:08 INFO - mozInstall == 1.12 08:12:08 INFO - mozcrash == 0.17 08:12:08 INFO - mozdebug == 0.1 08:12:08 INFO - mozdevice == 0.48 08:12:08 INFO - mozfile == 1.2 08:12:08 INFO - mozhttpd == 0.7 08:12:08 INFO - mozinfo == 0.9 08:12:08 INFO - mozleak == 0.1 08:12:08 INFO - mozlog == 3.4 08:12:08 INFO - moznetwork == 0.27 08:12:08 INFO - mozprocess == 0.23 08:12:08 INFO - mozprofile == 0.28 08:12:08 INFO - mozrunner == 6.12 08:12:08 INFO - mozscreenshot == 0.1 08:12:08 INFO - mozsystemmonitor == 0.3 08:12:08 INFO - moztest == 0.7 08:12:08 INFO - mozversion == 1.4 08:12:08 INFO - psutil == 3.1.1 08:12:08 INFO - requests == 1.2.3 08:12:08 INFO - wsgiref == 0.1.2 08:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:08 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')]} 08:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:08 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')]} 08:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:08 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:12:08 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 08:12:08 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 08:12:08 INFO - Using env: {'DISPLAY': ':0', 08:12:08 INFO - 'HOME': '/home/cltbld', 08:12:08 INFO - 'LANG': 'en_US.UTF-8', 08:12:08 INFO - 'LANGUAGE': 'en_US:en', 08:12:08 INFO - 'LOGNAME': 'cltbld', 08:12:08 INFO - 'MAIL': '/var/mail/cltbld', 08:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:08 INFO - 'MOZ_NO_REMOTE': '1', 08:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:08 INFO - 'NO_EM_RESTART': '1', 08:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:08 INFO - 'PWD': '/builds/slave/test', 08:12:08 INFO - 'SHELL': '/bin/bash', 08:12:08 INFO - 'SHLVL': '1', 08:12:08 INFO - 'TERM': 'linux', 08:12:08 INFO - 'TMOUT': '86400', 08:12:08 INFO - 'USER': 'cltbld', 08:12:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:08 INFO - '_': '/tools/buildbot/bin/python'} 08:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 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)) 08:12:08 INFO - Cleaning up... 08:12:08 INFO - Return code: 0 08:12:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:12:08 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')]} 08:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:08 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')]} 08:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:08 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 0x7effaf60bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', '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 08:12:08 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 08:12:08 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 08:12:08 INFO - Using env: {'DISPLAY': ':0', 08:12:08 INFO - 'HOME': '/home/cltbld', 08:12:08 INFO - 'LANG': 'en_US.UTF-8', 08:12:08 INFO - 'LANGUAGE': 'en_US:en', 08:12:08 INFO - 'LOGNAME': 'cltbld', 08:12:08 INFO - 'MAIL': '/var/mail/cltbld', 08:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:08 INFO - 'MOZ_NO_REMOTE': '1', 08:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:08 INFO - 'NO_EM_RESTART': '1', 08:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:08 INFO - 'PWD': '/builds/slave/test', 08:12:08 INFO - 'SHELL': '/bin/bash', 08:12:08 INFO - 'SHLVL': '1', 08:12:08 INFO - 'TERM': 'linux', 08:12:08 INFO - 'TMOUT': '86400', 08:12:08 INFO - 'USER': 'cltbld', 08:12:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:08 INFO - '_': '/tools/buildbot/bin/python'} 08:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:12:08 INFO - Cleaning up... 08:12:08 INFO - Return code: 0 08:12:08 INFO - Running post-action listener: _resource_record_post_action 08:12:08 INFO - Running post-action listener: _start_resource_monitoring 08:12:08 INFO - Starting resource monitoring. 08:12:08 INFO - [mozharness: 2016-09-17 15:12:08.742831Z] Finished create-virtualenv step (success) 08:12:08 INFO - [mozharness: 2016-09-17 15:12:08.743292Z] Running install step. 08:12:08 INFO - Running pre-action listener: _resource_record_pre_action 08:12:08 INFO - Running main action method: install 08:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:08 INFO - Reading from file tmpfile_stdout 08:12:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:12:09 INFO - Reading from file tmpfile_stdout 08:12:09 INFO - Output received: 08:12:09 INFO - Usage: mozinstall [options] installer 08:12:09 INFO - Options: 08:12:09 INFO - -h, --help show this help message and exit 08:12:09 INFO - -d DEST, --destination=DEST 08:12:09 INFO - Directory to install application into. [default: 08:12:09 INFO - "/builds/slave/test"] 08:12:09 INFO - --app=APP Application being installed. [default: firefox] 08:12:09 INFO - mkdir: /builds/slave/test/build/application 08:12:09 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'] 08:12:09 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 08:12:25 INFO - Reading from file tmpfile_stdout 08:12:25 INFO - Output received: 08:12:25 INFO - /builds/slave/test/build/application/firefox/firefox 08:12:25 INFO - Running post-action listener: _resource_record_post_action 08:12:26 INFO - [mozharness: 2016-09-17 15:12:26.000256Z] Finished install step (success) 08:12:26 INFO - [mozharness: 2016-09-17 15:12:26.000501Z] Running run-tests step. 08:12:26 INFO - Running pre-action listener: _resource_record_pre_action 08:12:26 INFO - Running main action method: run_tests 08:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:12:26 INFO - Python 2.7.3 08:12:26 INFO - Return code: 0 08:12:26 INFO - grabbing minidump binary from tooltool 08:12: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')]} 08:12: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 0x1119930>, '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 0x111a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111acc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:12: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 08:12: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 08:12: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 08:12:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:12:26 INFO - Return code: 0 08:12:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:12:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:12:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:12:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:12:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 08:12:26 INFO - Using env: {'DISPLAY': ':0', 08:12:26 INFO - 'HOME': '/home/cltbld', 08:12:26 INFO - 'LANG': 'en_US.UTF-8', 08:12:26 INFO - 'LANGUAGE': 'en_US:en', 08:12:26 INFO - 'LOGNAME': 'cltbld', 08:12:26 INFO - 'MAIL': '/var/mail/cltbld', 08:12:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:12:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:26 INFO - 'MOZ_NO_REMOTE': '1', 08:12:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:26 INFO - 'NO_EM_RESTART': '1', 08:12:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:26 INFO - 'PWD': '/builds/slave/test', 08:12:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:12:26 INFO - 'SHELL': '/bin/bash', 08:12:26 INFO - 'SHLVL': '1', 08:12:26 INFO - 'TERM': 'linux', 08:12:26 INFO - 'TMOUT': '86400', 08:12:26 INFO - 'USER': 'cltbld', 08:12:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832', 08:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:26 INFO - '_': '/tools/buildbot/bin/python'} 08:12:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:12:26 INFO - mozversion application_buildid: 20160916163001 08:12:26 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:12:26 INFO - mozversion application_display_name: Nightly 08:12:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:12:26 INFO - mozversion application_name: Firefox 08:12:26 INFO - mozversion application_remotingname: firefox 08:12:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:12:26 INFO - mozversion application_vendor: Mozilla 08:12:26 INFO - mozversion application_version: 51.0a1 08:12:26 INFO - mozversion platform_buildid: 20160916163001 08:12:26 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:12:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:12:26 INFO - mozversion platform_version: 51.0a1 08:12:26 INFO - using testdate: 1474125146 08:12:26 INFO - actual date: 1474125146 08:12:26 INFO - starting webserver on 'localhost:37046' 08:12:26 INFO - SUITE-START | Running 2 tests 08:12:26 INFO - TEST-START | dromaeo_css 08:12:26 INFO - operating with platform_type : linux_ 08:12:26 INFO - Initialising browser for dromaeo_css test... 08:12:26 INFO - TEST-INFO | started process 8454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvt14ML/profile http://localhost:37046/getInfo.html) 08:12:31 INFO - PROCESS | 8454 | __metrics Screen width/height:1600/1200 08:12:31 INFO - PROCESS | 8454 | colorDepth:24 08:12:31 INFO - PROCESS | 8454 | Browser inner width/height: 1024/697 08:12:31 INFO - PROCESS | 8454 | __metrics 08:12:31 INFO - PROCESS | 8454 | 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" 08:12:31 INFO - PROCESS | 8454 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:12:32 INFO - PROCESS | 8454 | console.error: PushService: 08:12:32 INFO - PROCESS | 8454 | stateChangeProcessEnqueue: Error transitioning state 08:12:32 INFO - PROCESS | 8454 | UnknownError 08:12:32 INFO - TEST-INFO | 8454: exit 0 08:12:32 INFO - Browser initialized. 08:12:32 INFO - Running cycle 1/1 for dromaeo_css test... 08:12:32 INFO - TEST-INFO | started process 8540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvt14ML/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:44 INFO - PROCESS | 8540 | RSS: Main: 177356800 08:12:44 INFO - PROCESS | 8540 | 08:12:53 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:12:54 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 2.01669 seconds. 08:13:03 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:13:19 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 08:13:27 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:13:36 INFO - PROCESS | 8540 | 1474125216312 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] 08:13:37 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:13:43 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:13:46 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 08:14:01 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:14:08 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 08:14:12 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:14:14 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 08:14:16 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:14:18 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:14:32 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:14:42 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:14:50 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 08:14:52 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:14:54 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:15:21 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:15:25 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:15:27 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:15:30 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:15:37 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:15:39 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:15:45 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37046/tests/dromaeo/cssquery-ext.html) 08:15:46 INFO - PROCESS | 8540 | RSS: Main: 319377408 08:15:46 INFO - PROCESS | 8540 | 08:15:55 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:16:13 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:16:40 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:16:52 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:01 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:17:10 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:26 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:17:32 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:40 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:17:55 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:18:00 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:18:11 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:18:41 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:18:49 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-ext.html (next: http://localhost:37046/tests/dromaeo/cssquery-jquery.html) 08:18:50 INFO - PROCESS | 8540 | RSS: Main: 271405056 08:18:50 INFO - PROCESS | 8540 | 08:18:53 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:18:55 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:19:02 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:19:08 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:19:24 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:26 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:19:42 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:19:46 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:20:15 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:27 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:20:37 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:20:51 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:13 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:21:21 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:24 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:27 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:21:37 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:39 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:49 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:21:51 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37046/tests/dromaeo/cssquery-mootools.html) 08:21:52 INFO - PROCESS | 8540 | RSS: Main: 272519168 08:21:52 INFO - PROCESS | 8540 | 08:21:58 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 08:22:13 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:22:25 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:29 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:22:31 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:22:35 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:22:38 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:22:53 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:22:55 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:23:03 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:23:13 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:23:25 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:23:33 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:23:40 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:23:56 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:00 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:24:10 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:24:40 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:54 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:24:54 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37046/tests/dromaeo/cssquery-prototype.html) 08:24:55 INFO - PROCESS | 8540 | RSS: Main: 268496896 08:24:55 INFO - PROCESS | 8540 | 08:24:58 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:25:14 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:25:20 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:22 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:25:36 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:42 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 08:25:44 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:25:52 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:25:54 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:26:22 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:26:26 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:26:31 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 08:26:35 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:26:39 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:26:53 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:27:09 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:27:15 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:22 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:27:39 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 08:27:49 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:27:57 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37046/tests/dromaeo/cssquery-yui.html) 08:27:58 INFO - PROCESS | 8540 | RSS: Main: 216719360 08:27:58 INFO - PROCESS | 8540 | 08:28:03 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:28:11 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:28:17 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:28:21 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:28:31 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:28:51 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00269 seconds. 08:29:07 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:29:15 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:29:32 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:29:59 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:30:20 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:30:24 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:30:57 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:31:01 INFO - PROCESS | 8540 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 08:31:01 INFO - PROCESS | 8540 | Cycle 1(1): loaded http://localhost:37046/tests/dromaeo/cssquery-yui.html (next: http://localhost:37046/tests/dromaeo/cssquery-dojo.html) 08:31:01 INFO - PROCESS | 8540 | RSS: Main: 306462720 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | __start_tp_report 08:31:01 INFO - PROCESS | 8540 | _x_x_mozilla_page_load 08:31:01 INFO - PROCESS | 8540 | _x_x_mozilla_page_load_details 08:31:01 INFO - PROCESS | 8540 | |i|pagename|runs| 08:31:01 INFO - PROCESS | 8540 | |0;dojo.html;2992.3716019635876;3030.499967505363;3034.742046926012;3060.693930606938;3082.537619357096;5052.621053420999;5123.333966584348;5163.870903227416;5238.397584277814;5255.553277971372;5155.5360017598405;5208.036513245052;5217.765200565974;5226.817061402851;5249.921251181236;4663.74349410784;4676;4735.810567577293;4742.928856067162;4753.762311884409;4777.546133117348;4816.97591512042;4827.56551910328;4829.444613869415;4863.829765958192;4591.816327346902;4638.257878739386;4676.766161691902;4704.411948506437;4747.548982846624;4722.7402492862875;4724.362211101492;4726.26742854858;4774.140654682155;4799.376081109468;4696.530346965276;4705.905881882343;4707.03505781314;4712.670113092085;4742.122707299126;4956.826511072131;5018.071656743514;5058.2412638104215;5132.897342053176;5142.177251639757;5464.289642346509;5467.097928841737;5467.972660136714;5471.917921231185;5522.502974732293;5499.862503437406;5500.972495137538;5505.807296744635;5515.365732940723;5590.664560126364;5051.06555287273;5093.23601459784;5095.057414378278;5154.561862241714;5171.922421163686;5692.23154874095;5697.515711164598;5754.4533269339345;5765.654060756368;5770.942290577041;5468.945310546923;5476.342838859362;5476.890462190813;5477.370102438191;5481.890362192734;5233.162693968947;5320.308359913264;5540.9168862467095;5562.582806289544;5631.7747290108755;5471.753771080311;5491.093969495068;5491.478309560585;5539.224508568804;5588.1059030555725;5536.723163841792;5549.139883318092;5551.333839939233;5555.138953462219;5557.249771280906;4125.5874412558505;4335.089631177425;4337.479502459725;4378.562143785596;4384.780760961939;3707.480952666629;3744.5506539215467;3783.0731470789833;3802.391617341212;3839.9616003839606;5168.664036837668;5171.948280517222;5172.456892026358;5174.689518628894;5227.895442091137;5472.863178420492;5479.315085614298;5480.561555075585;5482.451754824486;5548.889022219533;5033.546980771748;5123.538881500608;5124.641275110706;5154.845354639367;5211.869703257449;4739.265413860788;4758.4765675776325;4782.473927867932;4807.927881081787;4818.6626936114835;5610.635308704921;5617.101263797773;5617.32592088952;5619.915701264485;5674.886502270014;5354.089804733127;5427.891442171055;5499.477549632778;5508.476694713996;5509.118541033576;5557.3331200256225;5686.715664216856;5727.484526392478;5737;5825.912611310749;5484.424135465719;5487.094629386106;5487.396386397573;5490.780368785204;5493.450654934634;5522.9447705524035;5529.612927095065;5552.444755524415;5589.748461319169;5648.293963254593;5247.291615631841;5285.947140528546;5385.434529374359;5410.83767486976;5439.0753571893665;5406.107992181246;5410.107332290286;5410.891782164414;5465.754041068082;5478.369987451494;5328.440513746001;5336.119540275966;5337.759800808895;5339.332583427072;5400.702961337164;5318.175682769177;5358.651687640291;5377.247185394126;5431.131019036935;5433.157860531624;5238.528532432099;5322.6540274883555;5330.653507521999;5376.516113549799;5396.082665946877 08:31:01 INFO - PROCESS | 8540 | |1;ext.html;542.7495051401571;571.1432850723914;591.591801656857;599.6761748655726;602.6414283501315;32277;43917.34123988139;44716.552834471644;44730.32904506427;44827.55172448274;45690;46077.76961115198;46139;46178.538214617845;46189.53810461894;9090.363674542785;9256;9258.074192580756;9260.861087083666;9261.259099272076;9034.954825225865;9097.272218222526;9114.27085833135;9128.72613821583;9139.177474027336;9186.908130918671;9210.861837072449;9214.493202873839;9215.815683686322;9218.86171707425;9140.268778497737;9177.908220917772;9184.540772961358;9190.310726695525;9190.35667503275;9068.54657267134;9090.090990900857;9105.908940910573;9180.6786762463;9181;42590.36114458286;42679.57320426787;42985;42985.78507107476;43333.78333108314;3314.2045908981913;3425.8629654813776;3447.637998010223;3575.3564358415474;3615.403458429356;32005;32180.356392872247;32435;32498.67501325004;32624.184395390068;4293.699441039129;4399.186150562156;4431.202383570956;4458.576435238647;4535.478419981711;307698;310930;310982;311119.4444027788;311599;59061.40938590645;59445;59489.40510594839;59500.40499594949;59560.70219648874;52099.47900521022;54427.72786136123;55869.72065139729;55946.72026639922;56199;15211;15685.45100921485;15698.843011569737;16123.435679751152;16139.596510087342;12359.25844449336;12366.258024518558;12371.072169587316;12426.75146497065;12435.44040518179;15733.606659833596;16108.838911610734;17902.731459028124;17926.731099033525;17933.551661208574;6135.171751813537;6436.420722135031;6497.415232628991;6600.108985287021;6691.230508491489;915.3958387464241;974.2498276327331;1008.505832142245;1018.5467466977124;1091.956321747137;31508.527372089437;31867;31946.361072778414;32183;32867.34265314681;1043.8277684182176;1091.4051841746268;1103.448275862069;1138.1805100327751;1159.4029075026367;16917;17625;17849.375271865683;18479.630407391778;18573.628527429377;241744;242777;242996;243399;494370;58982.41017589855;59038.70480647569;59222.70388648029;59364.40635593675;59378.40621593815;1273.7834568123442;1322.642886420661;1332.1341128266704;1344.8117263583106;1346.2326473909777;30885.845570772002;32031.199220019687;32297;32656.34687306193;32928.53036022356;52661.7366913163;52920.735396324315;52958;53079.734601326745;53194.2020869695;426846;430252;431223;431571;432328;24782;25366.49267014686;27894.44211115807;28741;28885;47083.764581176874;47214.52785472101;47556.7622161887;47927.28109078437;49076;39798;40585.59414405818;40595.7970210147;41342.173156537305;42521;40474.59525404708;41074.38388424236;41880.79059604683;42117.78941105275;42830.35754463748;39733.60266397415;40382.39426408547;40667.7491273283;40884.182316354105;41934;34697.653023470455;36326.818365908;36701;37224.81387593045;37438.62561374351;27433.725662743116;27964.580531292453;27990.58014129749;28045.579316309864;28200.85899570489 08:31:01 INFO - PROCESS | 8540 | |2;jquery.html;381.34028131332775;384.46559282597195;395.34372940918075;395.48784324300027;417.7911044477759;14038.508652197175;14984.72629826466;17569.64860702785;19191.520211994688;19273.710894336597;13473.73052538951;14664.926675366634;17245.31018759248;17635.20641571129;17779.91110044451;5356.732163391835;5754.942450575482;5783.566232532577;5788.6816225107605;5790.826275211751;5551.444855514436;5722;5780.161876527901;5802.8104238631195;5807.535397168216;5708.828735137952;5755.6258843175265;5758.790653962673;5779.393163717812;5796.420357964212;5537.667739935577;5622.803201887914;5699.658020518782;5770.769169233246;5784.1902133701315;5108.795648174068;5121.103806833789;5125.436202017775;5125.436202017812;5129.0767661820855;18204.817951820445;21271;22025.33923982283;22120;22213.000414981365;2103.2112957640884;2173.1307477009163;2181.3237896252053;2284.348960546253;2298.276043046435;9679.660517121582;10030.9498452508;10196.439195844225;10288.74278143045;10698.732531686692;1879.5770951535794;1880.6802843516634;1898.411492437335;1901.9809801901943;1983.3653230966097;75308;77661.61169194174;78147.6092619539;78633.7093032556;80625.79061314143;10786.892131078588;11029.83455248172;11050.778984420267;11058.778824423467;11090.724566674775;15002.099874007376;15002.924985375003;15041.548753537412;15055.849441505445;15126.546203613909;6496.675166241669;6835.316468353125;7329.8900516492295;7879.448438609357;7986.600669966479;7059.01165841298;7148.606826624586;7177.856442871113;7324.157721861948;7340.4861659684375;4669.95330046702;4675.485696573374;4679.27471241958;4834.129856625771;4886.511348865156;1987.7117817916321;2005.2079428625611;2037.5619241862773;2048.8873111978983;2058.269314393369;916.1983264643437;917.4907926101077;953.4803532075035;974.5809302000208;1039.719275795531;17191.914040429718;17804;18702;18769.436916892515;18769.530761731065;914.095045904563;932.7668082979255;939.7321763297564;994.6369575105034;1041.6458404142627;9199.494027828534;10287.794244115075;10449;10467.267291289536;10485.475726213659;439491;441698;442604;456797;456898;84858.57570712107;85229;86802.56598716966;87517.56241218881;90268;968.9563969621383;973.3721749471692;979.8432250839832;996.1184351848666;998.6354980432235;15539.925938812716;16013.519594412186;16330;16786.4124755633;16941.576460588585;33205;39938.400923986745;41292.1741565173;42133;42470.575294246664;51179.744101279255;51434.74282628563;52462.21306680326;55333.44666553283;57618.42381576131;8063.233992770677;8296.336293096658;8374.53940033304;8464.788380290542;8614.22471977525;12019.699507512381;12138.939305303416;12888.806667900177;13129.343532823512;13330.333483325989;11305.321680699184;11508.42457877086;11550.249233799776;11681.357525336189;12086.15396922207;11325.660230193107;11497.425128743362;11555.768884622092;11711.648650540497;11897.821532676844;11207.327560346404;11399.259048161843;11610.70973225676;11661.76676466449;11692.415379231175;8240.423170378015;8484.363672724694;8747.125287471203;8756.299496040428;8801.295896328407;11256.662300131009;11504.25222360544;11627.360495172847;11724.941375293069;12143.574974875837 08:31:01 INFO - PROCESS | 8540 | |3;mootools.html;1293.8900193483553;1467.6110830629882;1530.8316085230624;1638.2545941596575;1781.9465416037515;7991.602519244226;8711.564421778909;8984.416012959153;8993.595288212038;9340;9114.361994660376;9175.128362805523;9187.173154416101;9190.172884440399;9194.35639505235;2402.975970240297;2429.8542087474807;2430.5746494363502;2431.307077482909;2431.3799981004818;2369.3839601703507;2394.341556072077;2411.144043864429;2419.1412048722714;2420.6611074449593;2363.3737059679197;2374.180907586888;2381.428457170275;2425.042108367194;2426.4176597616533;2335.2060299498253;2340.063974410241;2350.0129945422964;2357.1985524921524;2387.6657267982496;2310.549442858643;2311.514581937795;2312.167619656922;2316.66408370787;2323.117215458132;9046.954765226197;9089.590968406437;9127.361084724072;9190.448573085636;9202;5164.380274367062;5373.032854086263;5380.677159370412;5380.704061276629;5382.0043291991105;3359.7648164628486;3362.5628668272966;3398.9660103398896;3404.3191361727754;3412.0446275043028;1141.1555448967788;1143.0798207443006;1149.7010777197904;1164.4468877283223;1168.719507318246;12832.871671283261;12855.22868627876;12874.613761587167;12942.547010854574;12950.48198072076;4501.819927202875;4578.427696537933;4706.388169537938;4743.881402964954;4795.736234507066;10094.848577271347;10155.390676559282;10157.238207134495;10166.085052345177;10172.13536849376;5476.835694929158;5485.533729633026;5488.890222195613;5491.917621235684;5494.36814766299;4209.957900421018;4335.588119128678;4938.9259161111895;5014.674046186986;5049.722265275445;3895.0651843557343;4002.9799851000557;4024.8591299304385;4071.185762847442;4166.229247589205;1230.0958795285458;1243.2291978972914;1244.3964677131585;1255.987440125587;1262.9052821038458;941.2893265584577;975.8389865672085;985.2413641496008;988.5946761827616;1006.5470538257813;6100.481459076028;6124.846878827976;6145.96927015371;6174.073888916699;6338.936610633927;482.71278589239347;488.67014765034173;493.9209726443752;502.7159654795029;503.1120073071033;4248.575142485726;4278.850240241577;4417.801198946056;4509.940164061443;4530.139273538017;11274.323540587591;11772.411379431165;11775.705607359712;11779.705507362212;11787.351695656822;10853.565857365616;11650.126240531994;11671.533138674358;11676;11694.883051169549;8210.220029097227;8582.09887961755;8597.957010214908;8601.483910965362;8606.31149508038;5872.823815285548;6049.153118563493;6128.32588415265;6192.845178870564;6443.903341449788;10213.693589192337;10650.414227217578;10693;10768.623098191525;11015.449227538431;10970.945145274223;11039.834402483808;11040.944795275973;11100.611478598534;11143.72140696489;8010.359171266168;8056.556889371141;8121.71573994931;8300.501969881827;8302.70940517079;8702.347323950602;8774.780630484034;8776.78058048554;8783.824323513621;8788.472691638523;7930.524168549906;8043.83912321762;8088;8135.389845761473;8290.87563686533;7878.133405325524;7921.405894558046;7966.3626909848235;7984.081830589324;8042.155573664681;7161.641917904188;7404.370628496423;7431.888521672287;7489.850202995801;7507.962460187664;5224;5312.468753124656;5390.541803946709;5457.380457380559;5549.833504994856;5178.482151784942;5302.681839089667;5312.707801070978;5336.505778382059;5423.078076727044 08:31:01 INFO - PROCESS | 8540 | |4;prototype.html;329.89773170317204;333.5480424025445;336.65324715543;338.3706306270337;344.43512639271586;5124.564412024978;5195.844124676261;5197.818076367328;5208.63539552231;5232.816851410202;4954.677945933512;4998.600111991041;5078.390593128821;5090.592752579794;5171.146760784475;4082.2856000199995;4093.2018256440006;4102.589741025903;4107.856225032125;4127;4099.323611604097;4099.405586190001;4108.630223279904;4121.835126594948;4127.62851343379;3828.755654412313;3915.7650540967634;4096.487939007624;4105.733127346728;4106.917861642765;4082.6733861291027;4084.5098588169312;4085.795710214507;4091.8363265469347;4122.91754164915;3664.706823454144;3683.778973261613;3685.815709214555;3687;3691.3909204981146;4866.294387313819;5006.299118123466;5049.722265275408;5095.5159259870625;5098.97450512745;4695.225287827504;4714.703456549442;4734.739589322586;4737.8341758038305;4754.7147171169845;5367.973160134214;5382.6232163748555;5384.40771515133;5415;5427.6472029317965;3800.70775936183;3987.142764305688;4077.3272410052305;4086.5504794472586;4089.1003979124544;5571.498565129158;5625.212470254168;5629.268195134607;5640.407757185519;5658.292713410865;4110.856120035784;4138.275801734684;4138.813753381105;4142.357934520154;4143.937840932388;3993;4180.979095104505;4219.38818871262;4240.448741663564;4243.957560424418;4630.58324750774;4651.139539185261;4665.766711664403;4672.182368085571;4684.508126646653;5375.3280839895015;5377.865553361119;5385.249793817025;5449.700266485382;5451.836444906659;1390.492470248352;1417.8940426467166;1435.4904009076627;1440.7406666799866;1440.8343040550471;932.7201839448138;961.2118063188259;963.7686955130858;963.9662611808553;967.7435479597913;4487.237169681097;4635.2120139576355;4640.443146822403;4675.625949923998;4780.545848144421;4907.901841963141;4911.9476431943785;4913.806026083074;4956.182229932021;4966.801327946914;4664.790084446208;4669.229577119805;4687.648426368036;4709.929351059737;4717.481077081587;2618.633391325216;2621;2641.8546979915905;2655.6016597510143;2774.916752497428;5733.827985160452;5741.942580574224;5748;5749.511291540182;5749.88500229993;5659.434056594308;5688.23208866806;5694.629849059798;5697.886042279131;5722.399148089474;5598.804041858515;5614.389308971348;5652.689102099342;5696.629719068247;5738.368779434259;5507.421720719267;5532.3637781655625;5537.283442132791;5550.888982220252;5560.833175004756;5530.446955304437;5539.030669632879;5539.806106786243;5542.889142217214;5546.916796247979;5492.4507549246355;5754.978636215853;5757.8560535986935;5772.19189313488;5776.7111644418455;4047.959520404876;4069.4302797608952;4071.267171909085;4081.0001549619346;4096.426500289902;5397.298351214317;5464.699441530754;5531.668099914018;5564.805231816867;5699.316082070178;5449.155380915964;5453.318335208099;5454.536364409069;5468.835934921959;5471.2887324647545;5219.947800522098;5424.810131645373;5449.6185267032515;5465.403933131731;5474.726263686879;5310.814121505883;5320.680759154463;5368.946310536845;5381.784728610812;5409.7024663642305;5435.157550579666;5435.565154787529;5443.183522471661;5450.809221677222;5455.363390982769;5177.559907407913;5253.17176841269;5293.417724050429;5340.385855626652;5341.172118321666 08:31:01 INFO - PROCESS | 8540 | |5;yui.html;784.6508303804808;796.6295672512284;805.1867613710158;824.1593574553955;840.3697227079692;15702.371905123795;16498.91750541247;17502.212400441964;17514.21186046628;17563.473095807116;15370.308336124874;15716.37134514618;15722.135282559455;15723.37106515738;15737.84262157378;664.5713514782963;680.3740558685996;804.3364224514769;805.3999770171216;825.7522743177053;821.7370441458736;822.7367242482408;823.3865770001328;823.4812068396901;823.5964377455034;857.9442336248155;858.5020688000976;859.3812455032398;859.7463748194298;860.5826174305457;852.0499642898155;852.075498084579;853.4921721575653;857.0957639690124;857.34413173922;622.2501885228335;626.1671976271593;626.2735227136545;627.093849387635;627.3287582286955;14344.42622295107;14433.783493247609;14522.346494407777;14527.854721452755;14555.708885822225;15105.3202605883;15154.545363639108;15165.24173791306;15339.079655220723;15623.218839058001;11494.597689080842;11535.480903359285;11874.406279685982;12319.69200769979;12728.61814145577;4385.2106620808245;4519.819207231707;4529.818807247706;4639.976800116012;4723.881902952419;74372;76857.23142768556;77881;78157.21842781556;78166.6091669538;11004.339739615649;11120.666380008613;11122.666320010412;11266.324020558794;11564.537418503334;20366;20373.79626203719;20374.898125509277;20382.312068224095;20401.489962750755;7654.617269136521;7995.200479951958;8055.919440805634;8148.307393871469;8167.795805104801;4438.4451943507065;4486.730796152241;4643.883902902455;4646.883827904262;4712.52874712526;3200.48792193248;3315.6850099240532;3332.900012999614;3345.1469875181674;3345.1637090727318;605.340179204669;612.5161122713096;615.2033117113349;623.1494010675395;631.2740348599056;522.7718836186608;546.8359492152339;632.1781683811028;675.4157653629674;706.1244057368929;7560.773176804704;7642;8466.74599762008;8874.979377371556;9014.323925705467;579.7449122386221;610.1671218786324;619.7140932564856;628.4595248086641;693.2512886083119;4423.270160423558;4450.933236001462;4483.260262056724;4522.8643140705835;4523.163214805271;69488;69518;69824.65087674631;69978;71162.28837711661;97996;107085;111964;113699;117968;58773.706131469065;72247.63876180691;89867;91669;91946.08053919509;9984.97344316444;10207.438590877573;10338.328008679411;10430.843537347098;10509.9474502627;52135.21797173121;52378.214326784364;52573;53638.463615364904;54036.73302072594;74422;75193.62403188169;75845.620771898;77725;79124.60437697775;12947.287899165636;12992.870071299167;13155.342232888508;13251.933740331237;13534.120282181626;14753.557393278219;14832.332545035715;14945.476908308157;15238.542843714706;15672.138032408327;13755.656108597364;13859.168449893039;13950.372233249731;14378.568642940729;14601.269936503344;13891.652708682364;14229.928850355682;14265.71888465638;14280.214588197749;14317.713645727235;13331.266780327174;13374;13686.52097176594;13942.721145577234;14049.297535123407;11277.71805704831;11470.369129697947;11500.424978751196;11597.942010289895;11796.410179491162;11402.429878505876;11681.182317237712;11690.556366779008;11781.234219775688;12154.392280386122 08:31:01 INFO - PROCESS | 8540 | __end_tp_report 08:31:01 INFO - PROCESS | 8540 | __start_cc_report 08:31:01 INFO - PROCESS | 8540 | _x_x_mozilla_cycle_collect,1084 08:31:01 INFO - PROCESS | 8540 | __end_cc_report 08:31:01 INFO - PROCESS | 8540 | __startTimestamp1474126261847__endTimestamp 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | ------- Summary: start ------- 08:31:01 INFO - PROCESS | 8540 | Number of tests: 6 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#0] dojo.html Cycles:165 Average:5132.80 Median:5328.44 stddev:566.58 (10.6%) stddev-sans-first:542.87 08:31:01 INFO - PROCESS | 8540 | Values: 2992.4 3030.5 3034.7 3060.7 3082.5 5052.6 5123.3 5163.9 5238.4 5255.6 5155.5 5208.0 5217.8 5226.8 5249.9 4663.7 4676.0 4735.8 4742.9 4753.8 4777.5 4817.0 4827.6 4829.4 4863.8 4591.8 4638.3 4676.8 4704.4 4747.5 4722.7 4724.4 4726.3 4774.1 4799.4 4696.5 4705.9 4707.0 4712.7 4742.1 4956.8 5018.1 5058.2 5132.9 5142.2 5464.3 5467.1 5468.0 5471.9 5522.5 5499.9 5501.0 5505.8 5515.4 5590.7 5051.1 5093.2 5095.1 5154.6 5171.9 5692.2 5697.5 5754.5 5765.7 5770.9 5468.9 5476.3 5476.9 5477.4 5481.9 5233.2 5320.3 5540.9 5562.6 5631.8 5471.8 5491.1 5491.5 5539.2 5588.1 5536.7 5549.1 5551.3 5555.1 5557.2 4125.6 4335.1 4337.5 4378.6 4384.8 3707.5 3744.6 3783.1 3802.4 3840.0 5168.7 5171.9 5172.5 5174.7 5227.9 5472.9 5479.3 5480.6 5482.5 5548.9 5033.5 5123.5 5124.6 5154.8 5211.9 4739.3 4758.5 4782.5 4807.9 4818.7 5610.6 5617.1 5617.3 5619.9 5674.9 5354.1 5427.9 5499.5 5508.5 5509.1 5557.3 5686.7 5727.5 5737.0 5825.9 5484.4 5487.1 5487.4 5490.8 5493.5 5522.9 5529.6 5552.4 5589.7 5648.3 5247.3 5285.9 5385.4 5410.8 5439.1 5406.1 5410.1 5410.9 5465.8 5478.4 5328.4 5336.1 5337.8 5339.3 5400.7 5318.2 5358.7 5377.2 5431.1 5433.2 5238.5 5322.7 5330.7 5376.5 5396.1 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#1] ext.html Cycles:180 Average:52171.31 Median:42319.39 stddev:93527.15 (221.0%) stddev-sans-first:93709.19 08:31:01 INFO - PROCESS | 8540 | Values: 542.7 571.1 591.6 599.7 602.6 32277.0 43917.3 44716.6 44730.3 44827.6 45690.0 46077.8 46139.0 46178.5 46189.5 9090.4 9256.0 9258.1 9260.9 9261.3 9035.0 9097.3 9114.3 9128.7 9139.2 9186.9 9210.9 9214.5 9215.8 9218.9 9140.3 9177.9 9184.5 9190.3 9190.4 9068.5 9090.1 9105.9 9180.7 9181.0 42590.4 42679.6 42985.0 42985.8 43333.8 3314.2 3425.9 3447.6 3575.4 3615.4 32005.0 32180.4 32435.0 32498.7 32624.2 4293.7 4399.2 4431.2 4458.6 4535.5 307698.0 310930.0 310982.0 311119.4 311599.0 59061.4 59445.0 59489.4 59500.4 59560.7 52099.5 54427.7 55869.7 55946.7 56199.0 15211.0 15685.5 15698.8 16123.4 16139.6 12359.3 12366.3 12371.1 12426.8 12435.4 15733.6 16108.8 17902.7 17926.7 17933.6 6135.2 6436.4 6497.4 6600.1 6691.2 915.4 974.2 1008.5 1018.5 1092.0 31508.5 31867.0 31946.4 32183.0 32867.3 1043.8 1091.4 1103.4 1138.2 1159.4 16917.0 17625.0 17849.4 18479.6 18573.6 241744.0 242777.0 242996.0 243399.0 494370.0 58982.4 59038.7 59222.7 59364.4 59378.4 1273.8 1322.6 1332.1 1344.8 1346.2 30885.8 32031.2 32297.0 32656.3 32928.5 52661.7 52920.7 52958.0 53079.7 53194.2 426846.0 430252.0 431223.0 431571.0 432328.0 24782.0 25366.5 27894.4 28741.0 28885.0 47083.8 47214.5 47556.8 47927.3 49076.0 39798.0 40585.6 40595.8 41342.2 42521.0 40474.6 41074.4 41880.8 42117.8 42830.4 39733.6 40382.4 40667.7 40884.2 41934.0 34697.7 36326.8 36701.0 37224.8 37438.6 27433.7 27964.6 27990.6 28045.6 28200.9 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#2] jquery.html Cycles:180 Average:27055.55 Median:40615.29 stddev:73880.10 (181.9%) stddev-sans-first:74060.21 08:31:01 INFO - PROCESS | 8540 | Values: 381.3 384.5 395.3 395.5 417.8 14038.5 14984.7 17569.6 19191.5 19273.7 13473.7 14664.9 17245.3 17635.2 17779.9 5356.7 5754.9 5783.6 5788.7 5790.8 5551.4 5722.0 5780.2 5802.8 5807.5 5708.8 5755.6 5758.8 5779.4 5796.4 5537.7 5622.8 5699.7 5770.8 5784.2 5108.8 5121.1 5125.4 5125.4 5129.1 18204.8 21271.0 22025.3 22120.0 22213.0 2103.2 2173.1 2181.3 2284.3 2298.3 9679.7 10030.9 10196.4 10288.7 10698.7 1879.6 1880.7 1898.4 1902.0 1983.4 75308.0 77661.6 78147.6 78633.7 80625.8 10786.9 11029.8 11050.8 11058.8 11090.7 15002.1 15002.9 15041.5 15055.8 15126.5 6496.7 6835.3 7329.9 7879.4 7986.6 7059.0 7148.6 7177.9 7324.2 7340.5 4670.0 4675.5 4679.3 4834.1 4886.5 1987.7 2005.2 2037.6 2048.9 2058.3 916.2 917.5 953.5 974.6 1039.7 17191.9 17804.0 18702.0 18769.4 18769.5 914.1 932.8 939.7 994.6 1041.6 9199.5 10287.8 10449.0 10467.3 10485.5 439491.0 441698.0 442604.0 456797.0 456898.0 84858.6 85229.0 86802.6 87517.6 90268.0 969.0 973.4 979.8 996.1 998.6 15539.9 16013.5 16330.0 16786.4 16941.6 33205.0 39938.4 41292.2 42133.0 42470.6 51179.7 51434.7 52462.2 55333.4 57618.4 8063.2 8296.3 8374.5 8464.8 8614.2 12019.7 12138.9 12888.8 13129.3 13330.3 11305.3 11508.4 11550.2 11681.4 12086.2 11325.7 11497.4 11555.8 11711.6 11897.8 11207.3 11399.3 11610.7 11661.8 11692.4 8240.4 8484.4 8747.1 8756.3 8801.3 11256.7 11504.3 11627.4 11724.9 12143.6 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#3] mootools.html Cycles:180 Average:6008.23 Median:4725.13 stddev:3495.41 (74.0%) stddev-sans-first:3487.26 08:31:01 INFO - PROCESS | 8540 | Values: 1293.9 1467.6 1530.8 1638.3 1781.9 7991.6 8711.6 8984.4 8993.6 9340.0 9114.4 9175.1 9187.2 9190.2 9194.4 2403.0 2429.9 2430.6 2431.3 2431.4 2369.4 2394.3 2411.1 2419.1 2420.7 2363.4 2374.2 2381.4 2425.0 2426.4 2335.2 2340.1 2350.0 2357.2 2387.7 2310.5 2311.5 2312.2 2316.7 2323.1 9047.0 9089.6 9127.4 9190.4 9202.0 5164.4 5373.0 5380.7 5380.7 5382.0 3359.8 3362.6 3399.0 3404.3 3412.0 1141.2 1143.1 1149.7 1164.4 1168.7 12832.9 12855.2 12874.6 12942.5 12950.5 4501.8 4578.4 4706.4 4743.9 4795.7 10094.8 10155.4 10157.2 10166.1 10172.1 5476.8 5485.5 5488.9 5491.9 5494.4 4210.0 4335.6 4938.9 5014.7 5049.7 3895.1 4003.0 4024.9 4071.2 4166.2 1230.1 1243.2 1244.4 1256.0 1262.9 941.3 975.8 985.2 988.6 1006.5 6100.5 6124.8 6146.0 6174.1 6338.9 482.7 488.7 493.9 502.7 503.1 4248.6 4278.9 4417.8 4509.9 4530.1 11274.3 11772.4 11775.7 11779.7 11787.4 10853.6 11650.1 11671.5 11676.0 11694.9 8210.2 8582.1 8598.0 8601.5 8606.3 5872.8 6049.2 6128.3 6192.8 6443.9 10213.7 10650.4 10693.0 10768.6 11015.4 10970.9 11039.8 11040.9 11100.6 11143.7 8010.4 8056.6 8121.7 8300.5 8302.7 8702.3 8774.8 8776.8 8783.8 8788.5 7930.5 8043.8 8088.0 8135.4 8290.9 7878.1 7921.4 7966.4 7984.1 8042.2 7161.6 7404.4 7431.9 7489.9 7508.0 5224.0 5312.5 5390.5 5457.4 5549.8 5178.5 5302.7 5312.7 5336.5 5423.1 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#4] prototype.html Cycles:180 Average:4541.59 Median:5084.49 stddev:1316.69 (25.9%) stddev-sans-first:1281.88 08:31:01 INFO - PROCESS | 8540 | Values: 329.9 333.5 336.7 338.4 344.4 5124.6 5195.8 5197.8 5208.6 5232.8 4954.7 4998.6 5078.4 5090.6 5171.1 4082.3 4093.2 4102.6 4107.9 4127.0 4099.3 4099.4 4108.6 4121.8 4127.6 3828.8 3915.8 4096.5 4105.7 4106.9 4082.7 4084.5 4085.8 4091.8 4122.9 3664.7 3683.8 3685.8 3687.0 3691.4 4866.3 5006.3 5049.7 5095.5 5099.0 4695.2 4714.7 4734.7 4737.8 4754.7 5368.0 5382.6 5384.4 5415.0 5427.6 3800.7 3987.1 4077.3 4086.6 4089.1 5571.5 5625.2 5629.3 5640.4 5658.3 4110.9 4138.3 4138.8 4142.4 4143.9 3993.0 4181.0 4219.4 4240.4 4244.0 4630.6 4651.1 4665.8 4672.2 4684.5 5375.3 5377.9 5385.2 5449.7 5451.8 1390.5 1417.9 1435.5 1440.7 1440.8 932.7 961.2 963.8 964.0 967.7 4487.2 4635.2 4640.4 4675.6 4780.5 4907.9 4911.9 4913.8 4956.2 4966.8 4664.8 4669.2 4687.6 4709.9 4717.5 2618.6 2621.0 2641.9 2655.6 2774.9 5733.8 5741.9 5748.0 5749.5 5749.9 5659.4 5688.2 5694.6 5697.9 5722.4 5598.8 5614.4 5652.7 5696.6 5738.4 5507.4 5532.4 5537.3 5550.9 5560.8 5530.4 5539.0 5539.8 5542.9 5546.9 5492.5 5755.0 5757.9 5772.2 5776.7 4048.0 4069.4 4071.3 4081.0 4096.4 5397.3 5464.7 5531.7 5564.8 5699.3 5449.2 5453.3 5454.5 5468.8 5471.3 5219.9 5424.8 5449.6 5465.4 5474.7 5310.8 5320.7 5368.9 5381.8 5409.7 5435.2 5435.6 5443.2 5450.8 5455.4 5177.6 5253.2 5293.4 5340.4 5341.2 08:31:01 INFO - PROCESS | 8540 | 08:31:01 INFO - PROCESS | 8540 | [#5] yui.html Cycles:180 Average:19909.47 Median:4523.01 stddev:27603.75 (610.3%) stddev-sans-first:27643.84 08:31:01 INFO - PROCESS | 8540 | Values: 784.7 796.6 805.2 824.2 840.4 15702.4 16498.9 17502.2 17514.2 17563.5 15370.3 15716.4 15722.1 15723.4 15737.8 664.6 680.4 804.3 805.4 825.8 821.7 822.7 823.4 823.5 823.6 857.9 858.5 859.4 859.7 860.6 852.0 852.1 853.5 857.1 857.3 622.3 626.2 626.3 627.1 627.3 14344.4 14433.8 14522.3 14527.9 14555.7 15105.3 15154.5 15165.2 15339.1 15623.2 11494.6 11535.5 11874.4 12319.7 12728.6 4385.2 4519.8 4529.8 4640.0 4723.9 74372.0 76857.2 77881.0 78157.2 78166.6 11004.3 11120.7 11122.7 11266.3 11564.5 20366.0 20373.8 20374.9 20382.3 20401.5 7654.6 7995.2 8055.9 8148.3 8167.8 4438.4 4486.7 4643.9 4646.9 4712.5 3200.5 3315.7 3332.9 3345.1 3345.2 605.3 612.5 615.2 623.1 631.3 522.8 546.8 632.2 675.4 706.1 7560.8 7642.0 8466.7 8875.0 9014.3 579.7 610.2 619.7 628.5 693.3 4423.3 4450.9 4483.3 4522.9 4523.2 69488.0 69518.0 69824.7 69978.0 71162.3 97996.0 107085.0 111964.0 113699.0 117968.0 58773.7 72247.6 89867.0 91669.0 91946.1 9985.0 10207.4 10338.3 10430.8 10509.9 52135.2 52378.2 52573.0 53638.5 54036.7 74422.0 75193.6 75845.6 77725.0 79124.6 12947.3 12992.9 13155.3 13251.9 13534.1 14753.6 14832.3 14945.5 15238.5 15672.1 13755.7 13859.2 13950.4 14378.6 14601.3 13891.7 14229.9 14265.7 14280.2 14317.7 13331.3 13374.0 13686.5 13942.7 14049.3 11277.7 11470.4 11500.4 11597.9 11796.4 11402.4 11681.2 11690.6 11781.2 12154.4 08:31:01 INFO - PROCESS | 8540 | -------- Summary: end -------- 08:31:01 INFO - PROCESS | 8540 | 08:31:02 INFO - TEST-INFO | 8540: exit 0 08:31:02 INFO - TEST-OK | dromaeo_css | took 1116423ms 08:31:02 INFO - TEST-START | kraken 08:31:02 INFO - operating with platform_type : linux_ 08:31:02 INFO - Initialising browser for kraken test... 08:31:02 INFO - TEST-INFO | started process 8843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGGFKn5/profile http://localhost:37046/getInfo.html) 08:31:07 INFO - PROCESS | 8843 | __metrics Screen width/height:1600/1200 08:31:07 INFO - PROCESS | 8843 | colorDepth:24 08:31:07 INFO - PROCESS | 8843 | Browser inner width/height: 1024/697 08:31:07 INFO - PROCESS | 8843 | __metrics 08:31:07 INFO - PROCESS | 8843 | 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" 08:31:07 INFO - PROCESS | 8843 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:08 INFO - PROCESS | 8843 | console.error: PushService: 08:31:08 INFO - PROCESS | 8843 | stateChangeProcessEnqueue: Error transitioning state 08:31:08 INFO - PROCESS | 8843 | UnknownError 08:31:09 INFO - TEST-INFO | 8843: exit 0 08:31:09 INFO - Browser initialized. 08:31:09 INFO - Running cycle 1/1 for kraken test... 08:31:09 INFO - TEST-INFO | started process 8930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGGFKn5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:21 INFO - PROCESS | 8930 | RSS: Main: 173469696 08:31:21 INFO - PROCESS | 8930 | 08:31:23 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37046/tests/kraken/driver.html?testName=audio-beat-detection) 08:31:24 INFO - PROCESS | 8930 | RSS: Main: 241119232 08:31:24 INFO - PROCESS | 8930 | 08:31:27 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37046/tests/kraken/driver.html?testName=audio-dft) 08:31:27 INFO - PROCESS | 8930 | RSS: Main: 216035328 08:31:27 INFO - PROCESS | 8930 | 08:31:30 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37046/tests/kraken/driver.html?testName=audio-fft) 08:31:31 INFO - PROCESS | 8930 | RSS: Main: 298471424 08:31:31 INFO - PROCESS | 8930 | 08:31:33 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37046/tests/kraken/driver.html?testName=audio-oscillator) 08:31:34 INFO - PROCESS | 8930 | RSS: Main: 207265792 08:31:34 INFO - PROCESS | 8930 | 08:31:36 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37046/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:36 INFO - PROCESS | 8930 | RSS: Main: 212885504 08:31:36 INFO - PROCESS | 8930 | 08:31:39 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37046/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:40 INFO - PROCESS | 8930 | RSS: Main: 248676352 08:31:40 INFO - PROCESS | 8930 | 08:31:43 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37046/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:44 INFO - PROCESS | 8930 | RSS: Main: 247607296 08:31:44 INFO - PROCESS | 8930 | 08:31:47 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37046/tests/kraken/driver.html?testName=json-parse-financial) 08:31:47 INFO - PROCESS | 8930 | RSS: Main: 247857152 08:31:47 INFO - PROCESS | 8930 | 08:31:49 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37046/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:50 INFO - PROCESS | 8930 | RSS: Main: 209829888 08:31:50 INFO - PROCESS | 8930 | 08:31:51 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:52 INFO - PROCESS | 8930 | RSS: Main: 217501696 08:31:52 INFO - PROCESS | 8930 | 08:31:54 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:55 INFO - PROCESS | 8930 | RSS: Main: 221827072 08:31:55 INFO - PROCESS | 8930 | 08:31:57 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:31:58 INFO - PROCESS | 8930 | RSS: Main: 221970432 08:31:58 INFO - PROCESS | 8930 | 08:32:01 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:32:01 INFO - PROCESS | 8930 | RSS: Main: 221667328 08:32:01 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | Cycle 1(1): loaded http://localhost:37046/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37046/tests/kraken/driver.html?testName=ai-astar) 08:32:03 INFO - PROCESS | 8930 | RSS: Main: 272302080 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | __start_tp_report 08:32:03 INFO - PROCESS | 8930 | _x_x_mozilla_page_load 08:32:03 INFO - PROCESS | 8930 | _x_x_mozilla_page_load_details 08:32:03 INFO - PROCESS | 8930 | |i|pagename|runs| 08:32:03 INFO - PROCESS | 8930 | |0;ai-astar;108;111;112;111;111;111;111;109;109;110 08:32:03 INFO - PROCESS | 8930 | |1;audio-beat-detection;146;118;117;179;119;120;118;119;154;121 08:32:03 INFO - PROCESS | 8930 | |2;audio-dft;160;160;162;161;163;161;162;162;161;162 08:32:03 INFO - PROCESS | 8930 | |3;audio-fft;74;75;75;104;75;76;76;76;98;74 08:32:03 INFO - PROCESS | 8930 | |4;audio-oscillator;84;83;119;84;83;84;103;84;84;123 08:32:03 INFO - PROCESS | 8930 | |5;imaging-gaussian-blur;116;117;117;116;117;116;117;116;116;117 08:32:03 INFO - PROCESS | 8930 | |6;imaging-darkroom;146;153;146;146;146;146;146;146;146;146 08:32:03 INFO - PROCESS | 8930 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;96;95 08:32:03 INFO - PROCESS | 8930 | |8;json-parse-financial;53;52;50;51;51;52;51;50;50;53 08:32:03 INFO - PROCESS | 8930 | |9;json-stringify-tinderbox;59;59;59;59;61;60;60;60;60;61 08:32:03 INFO - PROCESS | 8930 | |10;stanford-crypto-aes;80;79;79;78;78;79;83;80;84;78 08:32:03 INFO - PROCESS | 8930 | |11;stanford-crypto-ccm;132;129;129;130;131;125;135;125;170;127 08:32:03 INFO - PROCESS | 8930 | |12;stanford-crypto-pbkdf2;166;170;166;168;164;166;168;164;168;168 08:32:03 INFO - PROCESS | 8930 | |13;stanford-crypto-sha256-iterative;68;67;67;66;67;65;65;66;65;65 08:32:03 INFO - PROCESS | 8930 | __end_tp_report 08:32:03 INFO - PROCESS | 8930 | __start_cc_report 08:32:03 INFO - PROCESS | 8930 | _x_x_mozilla_cycle_collect,1617 08:32:03 INFO - PROCESS | 8930 | __end_cc_report 08:32:03 INFO - PROCESS | 8930 | __startTimestamp1474126323641__endTimestamp 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | ------- Summary: start ------- 08:32:03 INFO - PROCESS | 8930 | Number of tests: 14 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:1.25 (1.1%) stddev-sans-first:1.01 08:32:03 INFO - PROCESS | 8930 | Values: 108.0 111.0 112.0 111.0 111.0 111.0 111.0 109.0 109.0 110.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:120.50 stddev:21.35 (17.7%) stddev-sans-first:21.95 08:32:03 INFO - PROCESS | 8930 | Values: 146.0 118.0 117.0 179.0 119.0 120.0 118.0 119.0 154.0 121.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 08:32:03 INFO - PROCESS | 8930 | Values: 160.0 160.0 162.0 161.0 163.0 161.0 162.0 162.0 161.0 162.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.50 stddev:11.03 (14.6%) stddev-sans-first:11.46 08:32:03 INFO - PROCESS | 8930 | Values: 74.0 75.0 75.0 104.0 75.0 76.0 76.0 76.0 98.0 74.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:84.00 stddev:15.92 (19.0%) stddev-sans-first:16.54 08:32:03 INFO - PROCESS | 8930 | Values: 84.0 83.0 119.0 84.0 83.0 84.0 103.0 84.0 84.0 123.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:32:03 INFO - PROCESS | 8930 | Values: 116.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 116.0 117.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.00 stddev:2.21 (1.5%) stddev-sans-first:2.33 08:32:03 INFO - PROCESS | 8930 | Values: 146.0 153.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:32:03 INFO - PROCESS | 8930 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:1.16 (2.3%) stddev-sans-first:1.05 08:32:03 INFO - PROCESS | 8930 | Values: 53.0 52.0 50.0 51.0 51.0 52.0 51.0 50.0 50.0 53.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.79 (1.3%) stddev-sans-first:0.78 08:32:03 INFO - PROCESS | 8930 | Values: 59.0 59.0 59.0 59.0 61.0 60.0 60.0 60.0 60.0 61.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:2.10 (2.6%) stddev-sans-first:2.22 08:32:03 INFO - PROCESS | 8930 | Values: 80.0 79.0 79.0 78.0 78.0 79.0 83.0 80.0 84.0 78.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:130.50 stddev:13.26 (10.2%) stddev-sans-first:14.05 08:32:03 INFO - PROCESS | 8930 | Values: 132.0 129.0 129.0 130.0 131.0 125.0 135.0 125.0 170.0 127.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:168.00 stddev:1.93 (1.2%) stddev-sans-first:2.03 08:32:03 INFO - PROCESS | 8930 | Values: 166.0 170.0 166.0 168.0 164.0 166.0 168.0 164.0 168.0 168.0 08:32:03 INFO - PROCESS | 8930 | 08:32:03 INFO - PROCESS | 8930 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.10 (1.7%) stddev-sans-first:0.93 08:32:03 INFO - PROCESS | 8930 | Values: 68.0 67.0 67.0 66.0 67.0 65.0 65.0 66.0 65.0 65.0 08:32:03 INFO - PROCESS | 8930 | -------- Summary: end -------- 08:32:03 INFO - PROCESS | 8930 | 08:32:04 INFO - TEST-INFO | 8930: exit 0 08:32:04 INFO - TEST-OK | kraken | took 62087ms 08:32:04 INFO - SUITE-END | took 1178s 08:32:04 INFO - Completed test suite (00:19:38) 08:32:04 INFO - javascript benchmark 08:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2992.3716019635876, 3030.499967505363, 3034.742046926012, 3060.693930606938, 3082.537619357096, 5052.621053420999, 5123.333966584348, 5163.870903227416, 5238.397584277814, 5255.553277971372, 5155.5360017598405, 5208.036513245052, 5217.765200565974, 5226.817061402851, 5249.921251181236, 4663.74349410784, 4676.0, 4735.810567577293, 4742.928856067162, 4753.762311884409, 4777.546133117348, 4816.97591512042, 4827.56551910328, 4829.444613869415, 4863.829765958192, 4591.816327346902, 4638.257878739386, 4676.766161691902, 4704.411948506437, 4747.548982846624, 4722.7402492862875, 4724.362211101492, 4726.26742854858, 4774.140654682155, 4799.376081109468, 4696.530346965276, 4705.905881882343, 4707.03505781314, 4712.670113092085, 4742.122707299126, 4956.826511072131, 5018.071656743514, 5058.2412638104215, 5132.897342053176, 5142.177251639757, 5464.289642346509, 5467.097928841737, 5467.972660136714, 5471.917921231185, 5522.502974732293, 5499.862503437406, 5500.972495137538, 5505.807296744635, 5515.365732940723, 5590.664560126364, 5051.06555287273, 5093.23601459784, 5095.057414378278, 5154.561862241714, 5171.922421163686, 5692.23154874095, 5697.515711164598, 5754.4533269339345, 5765.654060756368, 5770.942290577041, 5468.945310546923, 5476.342838859362, 5476.890462190813, 5477.370102438191, 5481.890362192734, 5233.162693968947, 5320.308359913264, 5540.9168862467095, 5562.582806289544, 5631.7747290108755, 5471.753771080311, 5491.093969495068, 5491.478309560585, 5539.224508568804, 5588.1059030555725, 5536.723163841792, 5549.139883318092, 5551.333839939233, 5555.138953462219, 5557.249771280906, 4125.5874412558505, 4335.089631177425, 4337.479502459725, 4378.562143785596, 4384.780760961939, 3707.480952666629, 3744.5506539215467, 3783.0731470789833, 3802.391617341212, 3839.9616003839606, 5168.664036837668, 5171.948280517222, 5172.456892026358, 5174.689518628894, 5227.895442091137, 5472.863178420492, 5479.315085614298, 5480.561555075585, 5482.451754824486, 5548.889022219533, 5033.546980771748, 5123.538881500608, 5124.641275110706, 5154.845354639367, 5211.869703257449, 4739.265413860788, 4758.4765675776325, 4782.473927867932, 4807.927881081787, 4818.6626936114835, 5610.635308704921, 5617.101263797773, 5617.32592088952, 5619.915701264485, 5674.886502270014, 5354.089804733127, 5427.891442171055, 5499.477549632778, 5508.476694713996, 5509.118541033576, 5557.3331200256225, 5686.715664216856, 5727.484526392478, 5737.0, 5825.912611310749, 5484.424135465719, 5487.094629386106, 5487.396386397573, 5490.780368785204, 5493.450654934634, 5522.9447705524035, 5529.612927095065, 5552.444755524415, 5589.748461319169, 5648.293963254593, 5247.291615631841, 5285.947140528546, 5385.434529374359, 5410.83767486976, 5439.0753571893665, 5406.107992181246, 5410.107332290286, 5410.891782164414, 5465.754041068082, 5478.369987451494, 5328.440513746001, 5336.119540275966, 5337.759800808895, 5339.332583427072, 5400.702961337164, 5318.175682769177, 5358.651687640291, 5377.247185394126, 5431.131019036935, 5433.157860531624, 5238.528532432099, 5322.6540274883555, 5330.653507521999, 5376.516113549799, 5396.082665946877], "value": 5095.813421717691, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.7495051401571, 571.1432850723914, 591.591801656857, 599.6761748655726, 602.6414283501315, 32277.0, 43917.34123988139, 44716.552834471644, 44730.32904506427, 44827.55172448274, 45690.0, 46077.76961115198, 46139.0, 46178.538214617845, 46189.53810461894, 9090.363674542785, 9256.0, 9258.074192580756, 9260.861087083666, 9261.259099272076, 9034.954825225865, 9097.272218222526, 9114.27085833135, 9128.72613821583, 9139.177474027336, 9186.908130918671, 9210.861837072449, 9214.493202873839, 9215.815683686322, 9218.86171707425, 9140.268778497737, 9177.908220917772, 9184.540772961358, 9190.310726695525, 9190.35667503275, 9068.54657267134, 9090.090990900857, 9105.908940910573, 9180.6786762463, 9181.0, 42590.36114458286, 42679.57320426787, 42985.0, 42985.78507107476, 43333.78333108314, 3314.2045908981913, 3425.8629654813776, 3447.637998010223, 3575.3564358415474, 3615.403458429356, 32005.0, 32180.356392872247, 32435.0, 32498.67501325004, 32624.184395390068, 4293.699441039129, 4399.186150562156, 4431.202383570956, 4458.576435238647, 4535.478419981711, 307698.0, 310930.0, 310982.0, 311119.4444027788, 311599.0, 59061.40938590645, 59445.0, 59489.40510594839, 59500.40499594949, 59560.70219648874, 52099.47900521022, 54427.72786136123, 55869.72065139729, 55946.72026639922, 56199.0, 15211.0, 15685.45100921485, 15698.843011569737, 16123.435679751152, 16139.596510087342, 12359.25844449336, 12366.258024518558, 12371.072169587316, 12426.75146497065, 12435.44040518179, 15733.606659833596, 16108.838911610734, 17902.731459028124, 17926.731099033525, 17933.551661208574, 6135.171751813537, 6436.420722135031, 6497.415232628991, 6600.108985287021, 6691.230508491489, 915.3958387464241, 974.2498276327331, 1008.505832142245, 1018.5467466977124, 1091.956321747137, 31508.527372089437, 31867.0, 31946.361072778414, 32183.0, 32867.34265314681, 1043.8277684182176, 1091.4051841746268, 1103.448275862069, 1138.1805100327751, 1159.4029075026367, 16917.0, 17625.0, 17849.375271865683, 18479.630407391778, 18573.628527429377, 241744.0, 242777.0, 242996.0, 243399.0, 494370.0, 58982.41017589855, 59038.70480647569, 59222.70388648029, 59364.40635593675, 59378.40621593815, 1273.7834568123442, 1322.642886420661, 1332.1341128266704, 1344.8117263583106, 1346.2326473909777, 30885.845570772002, 32031.199220019687, 32297.0, 32656.34687306193, 32928.53036022356, 52661.7366913163, 52920.735396324315, 52958.0, 53079.734601326745, 53194.2020869695, 426846.0, 430252.0, 431223.0, 431571.0, 432328.0, 24782.0, 25366.49267014686, 27894.44211115807, 28741.0, 28885.0, 47083.764581176874, 47214.52785472101, 47556.7622161887, 47927.28109078437, 49076.0, 39798.0, 40585.59414405818, 40595.7970210147, 41342.173156537305, 42521.0, 40474.59525404708, 41074.38388424236, 41880.79059604683, 42117.78941105275, 42830.35754463748, 39733.60266397415, 40382.39426408547, 40667.7491273283, 40884.182316354105, 41934.0, 34697.653023470455, 36326.818365908, 36701.0, 37224.81387593045, 37438.62561374351, 27433.725662743116, 27964.580531292453, 27990.58014129749, 28045.579316309864, 28200.85899570489], "value": 19609.343913906745, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.34028131332775, 384.46559282597195, 395.34372940918075, 395.48784324300027, 417.7911044477759, 14038.508652197175, 14984.72629826466, 17569.64860702785, 19191.520211994688, 19273.710894336597, 13473.73052538951, 14664.926675366634, 17245.31018759248, 17635.20641571129, 17779.91110044451, 5356.732163391835, 5754.942450575482, 5783.566232532577, 5788.6816225107605, 5790.826275211751, 5551.444855514436, 5722.0, 5780.161876527901, 5802.8104238631195, 5807.535397168216, 5708.828735137952, 5755.6258843175265, 5758.790653962673, 5779.393163717812, 5796.420357964212, 5537.667739935577, 5622.803201887914, 5699.658020518782, 5770.769169233246, 5784.1902133701315, 5108.795648174068, 5121.103806833789, 5125.436202017775, 5125.436202017812, 5129.0767661820855, 18204.817951820445, 21271.0, 22025.33923982283, 22120.0, 22213.000414981365, 2103.2112957640884, 2173.1307477009163, 2181.3237896252053, 2284.348960546253, 2298.276043046435, 9679.660517121582, 10030.9498452508, 10196.439195844225, 10288.74278143045, 10698.732531686692, 1879.5770951535794, 1880.6802843516634, 1898.411492437335, 1901.9809801901943, 1983.3653230966097, 75308.0, 77661.61169194174, 78147.6092619539, 78633.7093032556, 80625.79061314143, 10786.892131078588, 11029.83455248172, 11050.778984420267, 11058.778824423467, 11090.724566674775, 15002.099874007376, 15002.924985375003, 15041.548753537412, 15055.849441505445, 15126.546203613909, 6496.675166241669, 6835.316468353125, 7329.8900516492295, 7879.448438609357, 7986.600669966479, 7059.01165841298, 7148.606826624586, 7177.856442871113, 7324.157721861948, 7340.4861659684375, 4669.95330046702, 4675.485696573374, 4679.27471241958, 4834.129856625771, 4886.511348865156, 1987.7117817916321, 2005.2079428625611, 2037.5619241862773, 2048.8873111978983, 2058.269314393369, 916.1983264643437, 917.4907926101077, 953.4803532075035, 974.5809302000208, 1039.719275795531, 17191.914040429718, 17804.0, 18702.0, 18769.436916892515, 18769.530761731065, 914.095045904563, 932.7668082979255, 939.7321763297564, 994.6369575105034, 1041.6458404142627, 9199.494027828534, 10287.794244115075, 10449.0, 10467.267291289536, 10485.475726213659, 439491.0, 441698.0, 442604.0, 456797.0, 456898.0, 84858.57570712107, 85229.0, 86802.56598716966, 87517.56241218881, 90268.0, 968.9563969621383, 973.3721749471692, 979.8432250839832, 996.1184351848666, 998.6354980432235, 15539.925938812716, 16013.519594412186, 16330.0, 16786.4124755633, 16941.576460588585, 33205.0, 39938.400923986745, 41292.1741565173, 42133.0, 42470.575294246664, 51179.744101279255, 51434.74282628563, 52462.21306680326, 55333.44666553283, 57618.42381576131, 8063.233992770677, 8296.336293096658, 8374.53940033304, 8464.788380290542, 8614.22471977525, 12019.699507512381, 12138.939305303416, 12888.806667900177, 13129.343532823512, 13330.333483325989, 11305.321680699184, 11508.42457877086, 11550.249233799776, 11681.357525336189, 12086.15396922207, 11325.660230193107, 11497.425128743362, 11555.768884622092, 11711.648650540497, 11897.821532676844, 11207.327560346404, 11399.259048161843, 11610.70973225676, 11661.76676466449, 11692.415379231175, 8240.423170378015, 8484.363672724694, 8747.125287471203, 8756.299496040428, 8801.295896328407, 11256.662300131009, 11504.25222360544, 11627.360495172847, 11724.941375293069, 12143.574974875837], "value": 8872.626976351015, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.8900193483553, 1467.6110830629882, 1530.8316085230624, 1638.2545941596575, 1781.9465416037515, 7991.602519244226, 8711.564421778909, 8984.416012959153, 8993.595288212038, 9340.0, 9114.361994660376, 9175.128362805523, 9187.173154416101, 9190.172884440399, 9194.35639505235, 2402.975970240297, 2429.8542087474807, 2430.5746494363502, 2431.307077482909, 2431.3799981004818, 2369.3839601703507, 2394.341556072077, 2411.144043864429, 2419.1412048722714, 2420.6611074449593, 2363.3737059679197, 2374.180907586888, 2381.428457170275, 2425.042108367194, 2426.4176597616533, 2335.2060299498253, 2340.063974410241, 2350.0129945422964, 2357.1985524921524, 2387.6657267982496, 2310.549442858643, 2311.514581937795, 2312.167619656922, 2316.66408370787, 2323.117215458132, 9046.954765226197, 9089.590968406437, 9127.361084724072, 9190.448573085636, 9202.0, 5164.380274367062, 5373.032854086263, 5380.677159370412, 5380.704061276629, 5382.0043291991105, 3359.7648164628486, 3362.5628668272966, 3398.9660103398896, 3404.3191361727754, 3412.0446275043028, 1141.1555448967788, 1143.0798207443006, 1149.7010777197904, 1164.4468877283223, 1168.719507318246, 12832.871671283261, 12855.22868627876, 12874.613761587167, 12942.547010854574, 12950.48198072076, 4501.819927202875, 4578.427696537933, 4706.388169537938, 4743.881402964954, 4795.736234507066, 10094.848577271347, 10155.390676559282, 10157.238207134495, 10166.085052345177, 10172.13536849376, 5476.835694929158, 5485.533729633026, 5488.890222195613, 5491.917621235684, 5494.36814766299, 4209.957900421018, 4335.588119128678, 4938.9259161111895, 5014.674046186986, 5049.722265275445, 3895.0651843557343, 4002.9799851000557, 4024.8591299304385, 4071.185762847442, 4166.229247589205, 1230.0958795285458, 1243.2291978972914, 1244.3964677131585, 1255.987440125587, 1262.9052821038458, 941.2893265584577, 975.8389865672085, 985.2413641496008, 988.5946761827616, 1006.5470538257813, 6100.481459076028, 6124.846878827976, 6145.96927015371, 6174.073888916699, 6338.936610633927, 482.71278589239347, 488.67014765034173, 493.9209726443752, 502.7159654795029, 503.1120073071033, 4248.575142485726, 4278.850240241577, 4417.801198946056, 4509.940164061443, 4530.139273538017, 11274.323540587591, 11772.411379431165, 11775.705607359712, 11779.705507362212, 11787.351695656822, 10853.565857365616, 11650.126240531994, 11671.533138674358, 11676.0, 11694.883051169549, 8210.220029097227, 8582.09887961755, 8597.957010214908, 8601.483910965362, 8606.31149508038, 5872.823815285548, 6049.153118563493, 6128.32588415265, 6192.845178870564, 6443.903341449788, 10213.693589192337, 10650.414227217578, 10693.0, 10768.623098191525, 11015.449227538431, 10970.945145274223, 11039.834402483808, 11040.944795275973, 11100.611478598534, 11143.72140696489, 8010.359171266168, 8056.556889371141, 8121.71573994931, 8300.501969881827, 8302.70940517079, 8702.347323950602, 8774.780630484034, 8776.78058048554, 8783.824323513621, 8788.472691638523, 7930.524168549906, 8043.83912321762, 8088.0, 8135.389845761473, 8290.87563686533, 7878.133405325524, 7921.405894558046, 7966.3626909848235, 7984.081830589324, 8042.155573664681, 7161.641917904188, 7404.370628496423, 7431.888521672287, 7489.850202995801, 7507.962460187664, 5224.0, 5312.468753124656, 5390.541803946709, 5457.380457380559, 5549.833504994856, 5178.482151784942, 5302.681839089667, 5312.707801070978, 5336.505778382059, 5423.078076727044], "value": 4696.468716559583, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.89773170317204, 333.5480424025445, 336.65324715543, 338.3706306270337, 344.43512639271586, 5124.564412024978, 5195.844124676261, 5197.818076367328, 5208.63539552231, 5232.816851410202, 4954.677945933512, 4998.600111991041, 5078.390593128821, 5090.592752579794, 5171.146760784475, 4082.2856000199995, 4093.2018256440006, 4102.589741025903, 4107.856225032125, 4127.0, 4099.323611604097, 4099.405586190001, 4108.630223279904, 4121.835126594948, 4127.62851343379, 3828.755654412313, 3915.7650540967634, 4096.487939007624, 4105.733127346728, 4106.917861642765, 4082.6733861291027, 4084.5098588169312, 4085.795710214507, 4091.8363265469347, 4122.91754164915, 3664.706823454144, 3683.778973261613, 3685.815709214555, 3687.0, 3691.3909204981146, 4866.294387313819, 5006.299118123466, 5049.722265275408, 5095.5159259870625, 5098.97450512745, 4695.225287827504, 4714.703456549442, 4734.739589322586, 4737.8341758038305, 4754.7147171169845, 5367.973160134214, 5382.6232163748555, 5384.40771515133, 5415.0, 5427.6472029317965, 3800.70775936183, 3987.142764305688, 4077.3272410052305, 4086.5504794472586, 4089.1003979124544, 5571.498565129158, 5625.212470254168, 5629.268195134607, 5640.407757185519, 5658.292713410865, 4110.856120035784, 4138.275801734684, 4138.813753381105, 4142.357934520154, 4143.937840932388, 3993.0, 4180.979095104505, 4219.38818871262, 4240.448741663564, 4243.957560424418, 4630.58324750774, 4651.139539185261, 4665.766711664403, 4672.182368085571, 4684.508126646653, 5375.3280839895015, 5377.865553361119, 5385.249793817025, 5449.700266485382, 5451.836444906659, 1390.492470248352, 1417.8940426467166, 1435.4904009076627, 1440.7406666799866, 1440.8343040550471, 932.7201839448138, 961.2118063188259, 963.7686955130858, 963.9662611808553, 967.7435479597913, 4487.237169681097, 4635.2120139576355, 4640.443146822403, 4675.625949923998, 4780.545848144421, 4907.901841963141, 4911.9476431943785, 4913.806026083074, 4956.182229932021, 4966.801327946914, 4664.790084446208, 4669.229577119805, 4687.648426368036, 4709.929351059737, 4717.481077081587, 2618.633391325216, 2621.0, 2641.8546979915905, 2655.6016597510143, 2774.916752497428, 5733.827985160452, 5741.942580574224, 5748.0, 5749.511291540182, 5749.88500229993, 5659.434056594308, 5688.23208866806, 5694.629849059798, 5697.886042279131, 5722.399148089474, 5598.804041858515, 5614.389308971348, 5652.689102099342, 5696.629719068247, 5738.368779434259, 5507.421720719267, 5532.3637781655625, 5537.283442132791, 5550.888982220252, 5560.833175004756, 5530.446955304437, 5539.030669632879, 5539.806106786243, 5542.889142217214, 5546.916796247979, 5492.4507549246355, 5754.978636215853, 5757.8560535986935, 5772.19189313488, 5776.7111644418455, 4047.959520404876, 4069.4302797608952, 4071.267171909085, 4081.0001549619346, 4096.426500289902, 5397.298351214317, 5464.699441530754, 5531.668099914018, 5564.805231816867, 5699.316082070178, 5449.155380915964, 5453.318335208099, 5454.536364409069, 5468.835934921959, 5471.2887324647545, 5219.947800522098, 5424.810131645373, 5449.6185267032515, 5465.403933131731, 5474.726263686879, 5310.814121505883, 5320.680759154463, 5368.946310536845, 5381.784728610812, 5409.7024663642305, 5435.157550579666, 5435.565154787529, 5443.183522471661, 5450.809221677222, 5455.363390982769, 5177.559907407913, 5253.17176841269, 5293.417724050429, 5340.385855626652, 5341.172118321666], "value": 4128.509633881701, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.6508303804808, 796.6295672512284, 805.1867613710158, 824.1593574553955, 840.3697227079692, 15702.371905123795, 16498.91750541247, 17502.212400441964, 17514.21186046628, 17563.473095807116, 15370.308336124874, 15716.37134514618, 15722.135282559455, 15723.37106515738, 15737.84262157378, 664.5713514782963, 680.3740558685996, 804.3364224514769, 805.3999770171216, 825.7522743177053, 821.7370441458736, 822.7367242482408, 823.3865770001328, 823.4812068396901, 823.5964377455034, 857.9442336248155, 858.5020688000976, 859.3812455032398, 859.7463748194298, 860.5826174305457, 852.0499642898155, 852.075498084579, 853.4921721575653, 857.0957639690124, 857.34413173922, 622.2501885228335, 626.1671976271593, 626.2735227136545, 627.093849387635, 627.3287582286955, 14344.42622295107, 14433.783493247609, 14522.346494407777, 14527.854721452755, 14555.708885822225, 15105.3202605883, 15154.545363639108, 15165.24173791306, 15339.079655220723, 15623.218839058001, 11494.597689080842, 11535.480903359285, 11874.406279685982, 12319.69200769979, 12728.61814145577, 4385.2106620808245, 4519.819207231707, 4529.818807247706, 4639.976800116012, 4723.881902952419, 74372.0, 76857.23142768556, 77881.0, 78157.21842781556, 78166.6091669538, 11004.339739615649, 11120.666380008613, 11122.666320010412, 11266.324020558794, 11564.537418503334, 20366.0, 20373.79626203719, 20374.898125509277, 20382.312068224095, 20401.489962750755, 7654.617269136521, 7995.200479951958, 8055.919440805634, 8148.307393871469, 8167.795805104801, 4438.4451943507065, 4486.730796152241, 4643.883902902455, 4646.883827904262, 4712.52874712526, 3200.48792193248, 3315.6850099240532, 3332.900012999614, 3345.1469875181674, 3345.1637090727318, 605.340179204669, 612.5161122713096, 615.2033117113349, 623.1494010675395, 631.2740348599056, 522.7718836186608, 546.8359492152339, 632.1781683811028, 675.4157653629674, 706.1244057368929, 7560.773176804704, 7642.0, 8466.74599762008, 8874.979377371556, 9014.323925705467, 579.7449122386221, 610.1671218786324, 619.7140932564856, 628.4595248086641, 693.2512886083119, 4423.270160423558, 4450.933236001462, 4483.260262056724, 4522.8643140705835, 4523.163214805271, 69488.0, 69518.0, 69824.65087674631, 69978.0, 71162.28837711661, 97996.0, 107085.0, 111964.0, 113699.0, 117968.0, 58773.706131469065, 72247.63876180691, 89867.0, 91669.0, 91946.08053919509, 9984.97344316444, 10207.438590877573, 10338.328008679411, 10430.843537347098, 10509.9474502627, 52135.21797173121, 52378.214326784364, 52573.0, 53638.463615364904, 54036.73302072594, 74422.0, 75193.62403188169, 75845.620771898, 77725.0, 79124.60437697775, 12947.287899165636, 12992.870071299167, 13155.342232888508, 13251.933740331237, 13534.120282181626, 14753.557393278219, 14832.332545035715, 14945.476908308157, 15238.542843714706, 15672.138032408327, 13755.656108597364, 13859.168449893039, 13950.372233249731, 14378.568642940729, 14601.269936503344, 13891.652708682364, 14229.928850355682, 14265.71888465638, 14280.214588197749, 14317.713645727235, 13331.266780327174, 13374.0, 13686.52097176594, 13942.721145577234, 14049.297535123407, 11277.71805704831, 11470.369129697947, 11500.424978751196, 11597.942010289895, 11796.410179491162, 11402.429878505876, 11681.182317237712, 11690.556366779008, 11781.234219775688, 12154.392280386122], "value": 7467.998117427628, "unit": "score"}], "name": "dromaeo_css", "value": 7102.705917652112, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0, 109.0, 109.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 117.0, 179.0, 119.0, 120.0, 118.0, 119.0, 154.0, 121.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 163.0, 161.0, 162.0, 162.0, 161.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 104.0, 75.0, 76.0, 76.0, 76.0, 98.0, 74.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 84.0, 83.0, 84.0, 103.0, 84.0, 84.0, 123.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 50.0, 51.0, 51.0, 52.0, 51.0, 50.0, 50.0, 53.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 78.0, 78.0, 79.0, 83.0, 80.0, 84.0, 78.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 129.0, 129.0, 130.0, 131.0, 125.0, 135.0, 125.0, 170.0, 127.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 166.0, 168.0, 164.0, 166.0, 168.0, 164.0, 168.0, 168.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 67.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1491.6999999999996, "alertThreshold": 2.0}]} 08:32:04 INFO - Return code: 0 08:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:32:04 INFO - # TBPL SUCCESS # 08:32:04 INFO - Running post-action listener: _resource_record_post_action 08:32:04 INFO - [mozharness: 2016-09-17 15:32:04.952284Z] Finished run-tests step (success) 08:32:04 INFO - Running post-run listener: _resource_record_post_run 08:32:05 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401649664; Read time: 12; Write time: 290736 08:32:05 INFO - TinderboxPrint: CPU usage
13.1% 08:32:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:32:05 INFO - TinderboxPrint: I/O write bytes / time
401,649,664 / 290,736 08:32:05 INFO - TinderboxPrint: CPU idle
8,295.1 (86.9%) 08:32:05 INFO - TinderboxPrint: CPU user
1,193.0 (12.5%) 08:32:05 INFO - TinderboxPrint: Swap in / out
0 / 0 08:32:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110231552; Read time: 0; Write time: 99436 08:32:05 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 291418112; Read time: 0; Write time: 191300 08:32:05 INFO - Running post-run listener: _upload_blobber_files 08:32:05 INFO - Blob upload gear active. 08:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32:05 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 08:32:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:32:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:06 INFO - (blobuploader) - INFO - Done attempting. 08:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:10 INFO - (blobuploader) - INFO - Done attempting. 08:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:10 INFO - (blobuploader) - INFO - Done attempting. 08:32:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:10 INFO - Return code: 0 08:32:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58cfca0d73c12dbbf424ca5528ae99bb14abb8a5b50f078bc8550e94394b565f1686faa04269e86126aa88412b4d616970a27d994faebace2ee8346f5ffb82cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3086c03aaf7e92f3d50fb7a8b3e1f9f306a7453b32feada8a0d65734960e4d0369f6d621553786aa0776e0b2413b819e889f584097246e4ca2607aaa39571a56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/704fca941794becc8ac833e86cd0fbb89287142b8792c98c4bb3c9de5bf8b9f938c1f8657844b339874b63a3e0dabb16f5f87440332cedaf28a8b5b67dc74c9d"} 08:32:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:10 INFO - Contents: 08:32:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58cfca0d73c12dbbf424ca5528ae99bb14abb8a5b50f078bc8550e94394b565f1686faa04269e86126aa88412b4d616970a27d994faebace2ee8346f5ffb82cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3086c03aaf7e92f3d50fb7a8b3e1f9f306a7453b32feada8a0d65734960e4d0369f6d621553786aa0776e0b2413b819e889f584097246e4ca2607aaa39571a56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/704fca941794becc8ac833e86cd0fbb89287142b8792c98c4bb3c9de5bf8b9f938c1f8657844b339874b63a3e0dabb16f5f87440332cedaf28a8b5b67dc74c9d"} 08:32:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:10 INFO - Copying logs to upload dir... 08:32:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.144428 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-17 08:32:10.498920) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:10.502120) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58cfca0d73c12dbbf424ca5528ae99bb14abb8a5b50f078bc8550e94394b565f1686faa04269e86126aa88412b4d616970a27d994faebace2ee8346f5ffb82cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3086c03aaf7e92f3d50fb7a8b3e1f9f306a7453b32feada8a0d65734960e4d0369f6d621553786aa0776e0b2413b819e889f584097246e4ca2607aaa39571a56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/704fca941794becc8ac833e86cd0fbb89287142b8792c98c4bb3c9de5bf8b9f938c1f8657844b339874b63a3e0dabb16f5f87440332cedaf28a8b5b67dc74c9d"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011313 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58cfca0d73c12dbbf424ca5528ae99bb14abb8a5b50f078bc8550e94394b565f1686faa04269e86126aa88412b4d616970a27d994faebace2ee8346f5ffb82cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3086c03aaf7e92f3d50fb7a8b3e1f9f306a7453b32feada8a0d65734960e4d0369f6d621553786aa0776e0b2413b819e889f584097246e4ca2607aaa39571a56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/704fca941794becc8ac833e86cd0fbb89287142b8792c98c4bb3c9de5bf8b9f938c1f8657844b339874b63a3e0dabb16f5f87440332cedaf28a8b5b67dc74c9d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:10.527711) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:10.528023) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474125094.181426-740148832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004373 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:10.610597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:10.610912) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:10.611263) ========= ========= Total master_lag: 0.17 =========