builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1474125059.65 results: success (0) buildid: 20160917075419 builduid: be9d0096c20448da8244416f933b95a5 revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.654232) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.654705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.654995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.683302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.683760) ========= 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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:10:59-- 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% 37.9M=0s 2016-09-17 08:11:00 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093893 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.792071) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.792404) ========= 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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019265 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:59.842126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:10:59.842493) ========= 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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:11:00,147 truncating revision to first 12 chars 2016-09-17 08:11:00,147 Setting DEBUG logging. 2016-09-17 08:11:00,147 attempt 1/10 2016-09-17 08:11:00,147 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:01,582 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.827676 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:11:01.687963) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.688370) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.688795) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.689082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.702816) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-17 08:11:01.703195) ========= /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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:11:02 INFO - MultiFileLogger online at 20160917 08:11:02 in /builds/slave/test 08:11:02 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:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:02 INFO - {'append_to_log': False, 08:11:02 INFO - 'base_work_dir': '/builds/slave/test', 08:11:02 INFO - 'blob_upload_branch': 'Firefox', 08:11:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:02 INFO - 'branch': 'Firefox', 08:11:02 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:02 INFO - 'config_files': ('talos/linux_config.py',), 08:11:02 INFO - 'default_actions': ('clobber', 08:11:02 INFO - 'read-buildbot-config', 08:11:02 INFO - 'download-and-extract', 08:11:02 INFO - 'populate-webroot', 08:11:02 INFO - 'create-virtualenv', 08:11:02 INFO - 'install', 08:11:02 INFO - 'run-tests'), 08:11:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:02 INFO - 'download_minidump_stackwalk': True, 08:11:02 INFO - 'download_symbols': 'ondemand', 08:11:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:02 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:02 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:02 INFO - 'installer_path': 'installer.exe', 08:11:02 INFO - 'log_level': 'info', 08:11:02 INFO - 'log_name': 'talos', 08:11:02 INFO - 'log_to_console': True, 08:11:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:11:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:11:02 INFO - 'opt_config_files': (), 08:11:02 INFO - 'pip_index': False, 08:11:02 INFO - 'sps_profile': False, 08:11:02 INFO - 'sps_profile_interval': 0, 08:11:02 INFO - 'suite': 'dromaeojs', 08:11:02 INFO - 'system_bits': '32', 08:11:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:02 INFO - 'title': 'talos-linux64-ix-017', 08:11:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:02 INFO - 'use_talos_json': True, 08:11:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:02 INFO - 'work_dir': 'build'} 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.102833Z] Running clobber step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: clobber 08:11:02 INFO - rmtree: /builds/slave/test/build 08:11:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:02 INFO - Running post-action listener: _resource_record_post_action 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.563157Z] Finished clobber step (success) 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.563259Z] Running read-buildbot-config step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: read_buildbot_config 08:11:02 INFO - Using buildbot properties: 08:11:02 INFO - { 08:11:02 INFO - "project": "", 08:11:02 INFO - "product": "firefox", 08:11:02 INFO - "script_repo_revision": "production", 08:11:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:11:02 INFO - "repository": "", 08:11:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:11:02 INFO - "buildid": "20160917075419", 08:11:02 INFO - "stage_platform": "linux64-pgo", 08:11:02 INFO - "basedir": "/builds/slave/test", 08:11:02 INFO - "buildnumber": 876, 08:11:02 INFO - "platform": "ubuntu64_hw", 08:11:02 INFO - "mozci_request": { 08:11:02 INFO - "priority": -1, 08:11:02 INFO - "type": "trigger_all_talos_jobs", 08:11:02 INFO - "times": 6 08:11:02 INFO - }, 08:11:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:11:02 INFO - "slavebuilddir": "test", 08:11:02 INFO - "branch": "mozilla-central", 08:11:02 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 08:11:02 INFO - "slavename": "talos-linux64-ix-017", 08:11:02 INFO - "builduid": "be9d0096c20448da8244416f933b95a5", 08:11:02 INFO - "repo_path": "mozilla-central" 08:11:02 INFO - } 08:11:02 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:02 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:02 INFO - Running post-action listener: _resource_record_post_action 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.566060Z] Finished read-buildbot-config step (success) 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.566151Z] Running download-and-extract step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: download_and_extract 08:11:02 INFO - mkdir: /builds/slave/test/build/tests 08:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:02 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:02 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:02 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:02 INFO - Downloaded 1361 bytes. 08:11:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:02 INFO - Using the following test package requirements: 08:11:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:11:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:11:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'jsshell-linux-x86_64.zip'], 08:11:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:11:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:11:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:11:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:11:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:11:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:11:02 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:02 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:02 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:02 INFO - Expected file size: 21936347 08:11:07 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:07 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:07 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:07 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:07 INFO - Expected file size: 21936347 08:11:11 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:11 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:11 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:11 INFO - Expected file size: 14414048 08:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:12 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:12 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:12 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:12 INFO - Downloaded 59535581 bytes. 08:11:12 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:12 INFO - mkdir: /builds/slave/test/properties 08:11:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:12 INFO - Contents: 08:11:12 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:12 INFO - Running post-action listener: _resource_record_post_action 08:11:12 INFO - Running post-action listener: set_extra_try_arguments 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.972070Z] Finished download-and-extract step (success) 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.972301Z] Running populate-webroot step. 08:11:12 INFO - Running pre-action listener: _resource_record_pre_action 08:11:12 INFO - Running main action method: populate_webroot 08:11:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:12 INFO - '--tppagecycles', 08:11:12 INFO - '7'], 08:11:12 INFO - 'tests': ['tsvgm']}}, 08:11:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['damp', 'tps']}, 08:11:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['damp', 'tps']}, 08:11:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['dromaeo_dom']}, 08:11:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['basic_compositor_video']}, 08:11:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tsvgx', 08:11:12 INFO - 'tsvgr_opacity', 08:11:12 INFO - 'tart', 08:11:12 INFO - 'tscrollx', 08:11:12 INFO - 'cart']}, 08:11:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:12 INFO - 'tsvgr_opacity', 08:11:12 INFO - 'tart', 08:11:12 INFO - 'tscrollx', 08:11:12 INFO - 'cart']}, 08:11:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tp5o']}, 08:11:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['tp5o']}, 08:11:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s', 08:11:12 INFO - '--xperf_path', 08:11:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:12 INFO - 'tests': ['tp5n']}, 08:11:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--xperf_path', 08:11:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:12 INFO - 'tests': ['tp5n']}}, 08:11:12 INFO - 'talos.zip': {'path': '', 08:11:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:12 INFO - Running post-action listener: _resource_record_post_action 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.992201Z] Finished populate-webroot step (success) 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.992292Z] Running create-virtualenv step. 08:11:12 INFO - Running pre-action listener: _resource_record_pre_action 08:11:12 INFO - Running main action method: create_virtualenv 08:11:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:12 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:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:13 INFO - Using real prefix '/usr' 08:11:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:15 INFO - Installing pip.................done. 08:11:15 INFO - Return code: 0 08:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:15 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:15 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:15 INFO - Using env: {'DISPLAY': ':0', 08:11:15 INFO - 'HOME': '/home/cltbld', 08:11:15 INFO - 'LANG': 'en_US.UTF-8', 08:11:15 INFO - 'LANGUAGE': 'en_US:en', 08:11:15 INFO - 'LOGNAME': 'cltbld', 08:11:15 INFO - 'MAIL': '/var/mail/cltbld', 08:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:15 INFO - 'MOZ_NO_REMOTE': '1', 08:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:15 INFO - 'NO_EM_RESTART': '1', 08:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:15 INFO - 'PWD': '/builds/slave/test', 08:11:15 INFO - 'SHELL': '/bin/bash', 08:11:15 INFO - 'SHLVL': '1', 08:11:15 INFO - 'TERM': 'linux', 08:11:15 INFO - 'TMOUT': '86400', 08:11:15 INFO - 'USER': 'cltbld', 08:11:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:15 INFO - '_': '/tools/buildbot/bin/python'} 08:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:15 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:15 INFO - Cleaning up... 08:11:15 INFO - Return code: 0 08:11:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:15 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:15 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:15 INFO - Using env: {'DISPLAY': ':0', 08:11:15 INFO - 'HOME': '/home/cltbld', 08:11:15 INFO - 'LANG': 'en_US.UTF-8', 08:11:15 INFO - 'LANGUAGE': 'en_US:en', 08:11:15 INFO - 'LOGNAME': 'cltbld', 08:11:15 INFO - 'MAIL': '/var/mail/cltbld', 08:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:15 INFO - 'MOZ_NO_REMOTE': '1', 08:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:15 INFO - 'NO_EM_RESTART': '1', 08:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:15 INFO - 'PWD': '/builds/slave/test', 08:11:15 INFO - 'SHELL': '/bin/bash', 08:11:15 INFO - 'SHLVL': '1', 08:11:15 INFO - 'TERM': 'linux', 08:11:15 INFO - 'TMOUT': '86400', 08:11:15 INFO - 'USER': 'cltbld', 08:11:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:15 INFO - '_': '/tools/buildbot/bin/python'} 08:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:15 INFO - Downloading/unpacking psutil>=3.1.1 08:11:15 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:15 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:15 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:15 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:15 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:15 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:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:17 INFO - Installing collected packages: psutil 08:11:17 INFO - Running setup.py install for psutil 08:11:18 INFO - building 'psutil._psutil_linux' extension 08:11:18 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:18 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:18 INFO - building 'psutil._psutil_posix' extension 08:11:18 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:18 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:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:18 INFO - Successfully installed psutil 08:11:18 INFO - Cleaning up... 08:11:18 INFO - Return code: 0 08:11:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 08:11:18 INFO - 'HOME': '/home/cltbld', 08:11:18 INFO - 'LANG': 'en_US.UTF-8', 08:11:18 INFO - 'LANGUAGE': 'en_US:en', 08:11:18 INFO - 'LOGNAME': 'cltbld', 08:11:18 INFO - 'MAIL': '/var/mail/cltbld', 08:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:18 INFO - 'MOZ_NO_REMOTE': '1', 08:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:18 INFO - 'NO_EM_RESTART': '1', 08:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:18 INFO - 'PWD': '/builds/slave/test', 08:11:18 INFO - 'SHELL': '/bin/bash', 08:11:18 INFO - 'SHLVL': '1', 08:11:18 INFO - 'TERM': 'linux', 08:11:18 INFO - 'TMOUT': '86400', 08:11:18 INFO - 'USER': 'cltbld', 08:11:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:18 INFO - '_': '/tools/buildbot/bin/python'} 08:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11:18 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:18 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:18 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:18 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:18 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:18 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:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:20 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:20 INFO - Installing collected packages: mozsystemmonitor 08:11:20 INFO - Running setup.py install for mozsystemmonitor 08:11:20 INFO - Successfully installed mozsystemmonitor 08:11:20 INFO - Cleaning up... 08:11:20 INFO - Return code: 0 08:11:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:11:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:20 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:20 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:20 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:20 INFO - Using env: {'DISPLAY': ':0', 08:11:20 INFO - 'HOME': '/home/cltbld', 08:11:20 INFO - 'LANG': 'en_US.UTF-8', 08:11:20 INFO - 'LANGUAGE': 'en_US:en', 08:11:20 INFO - 'LOGNAME': 'cltbld', 08:11:20 INFO - 'MAIL': '/var/mail/cltbld', 08:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:20 INFO - 'MOZ_NO_REMOTE': '1', 08:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:20 INFO - 'NO_EM_RESTART': '1', 08:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:20 INFO - 'PWD': '/builds/slave/test', 08:11:20 INFO - 'SHELL': '/bin/bash', 08:11:20 INFO - 'SHLVL': '1', 08:11:20 INFO - 'TERM': 'linux', 08:11:20 INFO - 'TMOUT': '86400', 08:11:20 INFO - 'USER': 'cltbld', 08:11:20 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:20 INFO - '_': '/tools/buildbot/bin/python'} 08:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:11:21 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:21 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:21 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:21 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:21 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:21 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:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:11:22 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:22 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:22 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:22 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:22 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:22 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:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:23 INFO - warning: no files found matching '*.txt' 08:11:23 INFO - no previously-included directories found matching 'build' 08:11:23 INFO - no previously-included directories found matching 'dist' 08:11:23 INFO - no previously-included directories found matching '.git*' 08:11:23 INFO - Installing collected packages: jsonschema, functools32 08:11:23 INFO - Running setup.py install for functools32 08:11:23 INFO - warning: no files found matching '*.txt' 08:11:23 INFO - no previously-included directories found matching 'build' 08:11:23 INFO - no previously-included directories found matching 'dist' 08:11:23 INFO - no previously-included directories found matching '.git*' 08:11:23 INFO - Successfully installed jsonschema functools32 08:11:23 INFO - Cleaning up... 08:11:23 INFO - Return code: 0 08:11:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:11:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:23 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:23 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:23 INFO - Using env: {'DISPLAY': ':0', 08:11:23 INFO - 'HOME': '/home/cltbld', 08:11:23 INFO - 'LANG': 'en_US.UTF-8', 08:11:23 INFO - 'LANGUAGE': 'en_US:en', 08:11:23 INFO - 'LOGNAME': 'cltbld', 08:11:23 INFO - 'MAIL': '/var/mail/cltbld', 08:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:23 INFO - 'MOZ_NO_REMOTE': '1', 08:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:23 INFO - 'NO_EM_RESTART': '1', 08:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:23 INFO - 'PWD': '/builds/slave/test', 08:11:23 INFO - 'SHELL': '/bin/bash', 08:11:23 INFO - 'SHLVL': '1', 08:11:23 INFO - 'TERM': 'linux', 08:11:23 INFO - 'TMOUT': '86400', 08:11:23 INFO - 'USER': 'cltbld', 08:11:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:23 INFO - '_': '/tools/buildbot/bin/python'} 08:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:11:23 INFO - Cleaning up... 08:11:23 INFO - Return code: 0 08:11:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:11:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:23 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:23 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:23 INFO - Using env: {'DISPLAY': ':0', 08:11:23 INFO - 'HOME': '/home/cltbld', 08:11:23 INFO - 'LANG': 'en_US.UTF-8', 08:11:23 INFO - 'LANGUAGE': 'en_US:en', 08:11:23 INFO - 'LOGNAME': 'cltbld', 08:11:23 INFO - 'MAIL': '/var/mail/cltbld', 08:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:23 INFO - 'MOZ_NO_REMOTE': '1', 08:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:23 INFO - 'NO_EM_RESTART': '1', 08:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:23 INFO - 'PWD': '/builds/slave/test', 08:11:23 INFO - 'SHELL': '/bin/bash', 08:11:23 INFO - 'SHLVL': '1', 08:11:23 INFO - 'TERM': 'linux', 08:11:23 INFO - 'TMOUT': '86400', 08:11:23 INFO - 'USER': 'cltbld', 08:11:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:23 INFO - '_': '/tools/buildbot/bin/python'} 08:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:23 INFO - Downloading/unpacking blobuploader==1.2.4 08:11:23 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:23 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:23 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:23 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:23 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:23 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:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:11:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:11:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Downloading docopt-0.6.1.tar.gz 08:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:11:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:11:26 INFO - Running setup.py install for blobuploader 08:11:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:11:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:11:26 INFO - Running setup.py install for requests 08:11:26 INFO - Running setup.py install for docopt 08:11:27 INFO - Successfully installed blobuploader requests docopt 08:11:27 INFO - Cleaning up... 08:11:27 INFO - Return code: 0 08:11:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:27 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:27 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:11:27 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:11:27 INFO - Using env: {'DISPLAY': ':0', 08:11:27 INFO - 'HOME': '/home/cltbld', 08:11:27 INFO - 'LANG': 'en_US.UTF-8', 08:11:27 INFO - 'LANGUAGE': 'en_US:en', 08:11:27 INFO - 'LOGNAME': 'cltbld', 08:11:27 INFO - 'MAIL': '/var/mail/cltbld', 08:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:27 INFO - 'MOZ_NO_REMOTE': '1', 08:11:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:27 INFO - 'NO_EM_RESTART': '1', 08:11:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:27 INFO - 'PWD': '/builds/slave/test', 08:11:27 INFO - 'SHELL': '/bin/bash', 08:11:27 INFO - 'SHLVL': '1', 08:11:27 INFO - 'TERM': 'linux', 08:11:27 INFO - 'TMOUT': '86400', 08:11:27 INFO - 'USER': 'cltbld', 08:11:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:27 INFO - '_': '/tools/buildbot/bin/python'} 08:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:27 INFO - Running setup.py (path:/tmp/pip-gnD_qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:27 INFO - Running setup.py (path:/tmp/pip-BRb6Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:27 INFO - Running setup.py (path:/tmp/pip-WiKpjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:27 INFO - Running setup.py (path:/tmp/pip-pMIt2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:27 INFO - Running setup.py (path:/tmp/pip-LMkebw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:27 INFO - Running setup.py (path:/tmp/pip-kTOAcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:27 INFO - Running setup.py (path:/tmp/pip-y5Pj2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:27 INFO - Running setup.py (path:/tmp/pip-wD35SA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:27 INFO - Running setup.py (path:/tmp/pip-vI55fM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:27 INFO - Running setup.py (path:/tmp/pip-LU2x15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:28 INFO - Running setup.py (path:/tmp/pip-CTaKtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:28 INFO - Running setup.py (path:/tmp/pip-qAzqsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:28 INFO - Running setup.py (path:/tmp/pip-HbKmgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:28 INFO - Running setup.py (path:/tmp/pip-3O02g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:28 INFO - Running setup.py (path:/tmp/pip-DPfd2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:28 INFO - Running setup.py (path:/tmp/pip-beOOQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:28 INFO - Running setup.py (path:/tmp/pip-M9htmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:28 INFO - Running setup.py install for manifestparser 08:11:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozcrash 08:11:28 INFO - Running setup.py install for mozdebug 08:11:28 INFO - Running setup.py install for mozdevice 08:11:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozfile 08:11:29 INFO - Running setup.py install for mozhttpd 08:11:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozinfo 08:11:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozInstall 08:11:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozleak 08:11:29 INFO - Running setup.py install for mozlog 08:11:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for moznetwork 08:11:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozprocess 08:11:29 INFO - Running setup.py install for mozprofile 08:11:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozrunner 08:11:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:30 INFO - Running setup.py install for mozscreenshot 08:11:30 INFO - Running setup.py install for moztest 08:11:30 INFO - Running setup.py install for mozversion 08:11:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:30 INFO - Cleaning up... 08:11:30 INFO - Return code: 0 08:11:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:30 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:11:30 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:11:30 INFO - Using env: {'DISPLAY': ':0', 08:11:30 INFO - 'HOME': '/home/cltbld', 08:11:30 INFO - 'LANG': 'en_US.UTF-8', 08:11:30 INFO - 'LANGUAGE': 'en_US:en', 08:11:30 INFO - 'LOGNAME': 'cltbld', 08:11:30 INFO - 'MAIL': '/var/mail/cltbld', 08:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:30 INFO - 'MOZ_NO_REMOTE': '1', 08:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:30 INFO - 'NO_EM_RESTART': '1', 08:11:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:30 INFO - 'PWD': '/builds/slave/test', 08:11:30 INFO - 'SHELL': '/bin/bash', 08:11:30 INFO - 'SHLVL': '1', 08:11:30 INFO - 'TERM': 'linux', 08:11:30 INFO - 'TMOUT': '86400', 08:11:30 INFO - 'USER': 'cltbld', 08:11:30 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:30 INFO - '_': '/tools/buildbot/bin/python'} 08:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:30 INFO - Running setup.py (path:/tmp/pip-x9CJVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:30 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:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:30 INFO - Running setup.py (path:/tmp/pip-4HuWcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:30 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:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:30 INFO - Running setup.py (path:/tmp/pip-njXLKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:30 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:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:30 INFO - Running setup.py (path:/tmp/pip-RkpYpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:30 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:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:30 INFO - Running setup.py (path:/tmp/pip-7gtZg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:30 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:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:30 INFO - Running setup.py (path:/tmp/pip-oVhZDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:31 INFO - Running setup.py (path:/tmp/pip-N5NF4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:31 INFO - Running setup.py (path:/tmp/pip-2Ai1ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:31 INFO - Running setup.py (path:/tmp/pip-Qu5UCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:31 INFO - Running setup.py (path:/tmp/pip-xeCGX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:31 INFO - Running setup.py (path:/tmp/pip-uWJ3rQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:31 INFO - Running setup.py (path:/tmp/pip-CIkL4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:31 INFO - Running setup.py (path:/tmp/pip-ZbZEkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:31 INFO - Running setup.py (path:/tmp/pip-qxwSpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:31 INFO - Running setup.py (path:/tmp/pip-cWWVFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:31 INFO - Running setup.py (path:/tmp/pip-gw31yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:31 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:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:31 INFO - Running setup.py (path:/tmp/pip-nwgZxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:31 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:11:31 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:11:31 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:11:31 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:11:31 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:11:31 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:11:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:33 INFO - Downloading blessings-1.6.tar.gz 08:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:33 INFO - Installing collected packages: blessings 08:11:33 INFO - Running setup.py install for blessings 08:11:33 INFO - Successfully installed blessings 08:11:33 INFO - Cleaning up... 08:11:33 INFO - Return code: 0 08:11:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Current package versions: 08:11:34 INFO - argparse == 1.2.1 08:11:34 INFO - blessings == 1.6 08:11:34 INFO - blobuploader == 1.2.4 08:11:34 INFO - docopt == 0.6.1 08:11:34 INFO - functools32 == 3.2.3-2 08:11:34 INFO - jsonschema == 2.5.1 08:11:34 INFO - manifestparser == 1.1 08:11:34 INFO - mozInstall == 1.12 08:11:34 INFO - mozcrash == 0.17 08:11:34 INFO - mozdebug == 0.1 08:11:34 INFO - mozdevice == 0.48 08:11:34 INFO - mozfile == 1.2 08:11:34 INFO - mozhttpd == 0.7 08:11:34 INFO - mozinfo == 0.9 08:11:34 INFO - mozleak == 0.1 08:11:34 INFO - mozlog == 3.4 08:11:34 INFO - moznetwork == 0.27 08:11:34 INFO - mozprocess == 0.23 08:11:34 INFO - mozprofile == 0.28 08:11:34 INFO - mozrunner == 6.12 08:11:34 INFO - mozscreenshot == 0.1 08:11:34 INFO - mozsystemmonitor == 0.3 08:11:34 INFO - moztest == 0.7 08:11:34 INFO - mozversion == 1.4 08:11:34 INFO - psutil == 3.1.1 08:11:34 INFO - requests == 1.2.3 08:11:34 INFO - wsgiref == 0.1.2 08:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:34 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 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:34 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:11:34 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:11:34 INFO - Using env: {'DISPLAY': ':0', 08:11:34 INFO - 'HOME': '/home/cltbld', 08:11:34 INFO - 'LANG': 'en_US.UTF-8', 08:11:34 INFO - 'LANGUAGE': 'en_US:en', 08:11:34 INFO - 'LOGNAME': 'cltbld', 08:11:34 INFO - 'MAIL': '/var/mail/cltbld', 08:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:34 INFO - 'MOZ_NO_REMOTE': '1', 08:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:34 INFO - 'NO_EM_RESTART': '1', 08:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:34 INFO - 'PWD': '/builds/slave/test', 08:11:34 INFO - 'SHELL': '/bin/bash', 08:11:34 INFO - 'SHLVL': '1', 08:11:34 INFO - 'TERM': 'linux', 08:11:34 INFO - 'TMOUT': '86400', 08:11:34 INFO - 'USER': 'cltbld', 08:11:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:34 INFO - '_': '/tools/buildbot/bin/python'} 08:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 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:11:34 INFO - Cleaning up... 08:11:34 INFO - Return code: 0 08:11:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f77e4b46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18148a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, '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': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', '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:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:11:34 INFO - Using env: {'DISPLAY': ':0', 08:11:34 INFO - 'HOME': '/home/cltbld', 08:11:34 INFO - 'LANG': 'en_US.UTF-8', 08:11:34 INFO - 'LANGUAGE': 'en_US:en', 08:11:34 INFO - 'LOGNAME': 'cltbld', 08:11:34 INFO - 'MAIL': '/var/mail/cltbld', 08:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:34 INFO - 'MOZ_NO_REMOTE': '1', 08:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:34 INFO - 'NO_EM_RESTART': '1', 08:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:34 INFO - 'PWD': '/builds/slave/test', 08:11:34 INFO - 'SHELL': '/bin/bash', 08:11:34 INFO - 'SHLVL': '1', 08:11:34 INFO - 'TERM': 'linux', 08:11:34 INFO - 'TMOUT': '86400', 08:11:34 INFO - 'USER': 'cltbld', 08:11:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:34 INFO - '_': '/tools/buildbot/bin/python'} 08:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:34 INFO - Cleaning up... 08:11:34 INFO - Return code: 0 08:11:34 INFO - Running post-action listener: _resource_record_post_action 08:11:34 INFO - Running post-action listener: _start_resource_monitoring 08:11:34 INFO - Starting resource monitoring. 08:11:34 INFO - [mozharness: 2016-09-17 15:11:34.672036Z] Finished create-virtualenv step (success) 08:11:34 INFO - [mozharness: 2016-09-17 15:11:34.672492Z] Running install step. 08:11:34 INFO - Running pre-action listener: _resource_record_pre_action 08:11:34 INFO - Running main action method: install 08:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Output received: 08:11:34 INFO - Usage: mozinstall [options] installer 08:11:34 INFO - Options: 08:11:34 INFO - -h, --help show this help message and exit 08:11:34 INFO - -d DEST, --destination=DEST 08:11:34 INFO - Directory to install application into. [default: 08:11:34 INFO - "/builds/slave/test"] 08:11:34 INFO - --app=APP Application being installed. [default: firefox] 08:11:34 INFO - mkdir: /builds/slave/test/build/application 08:11:34 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:11:34 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:11:51 INFO - Reading from file tmpfile_stdout 08:11:51 INFO - Output received: 08:11:51 INFO - /builds/slave/test/build/application/firefox/firefox 08:11:51 INFO - Running post-action listener: _resource_record_post_action 08:11:51 INFO - [mozharness: 2016-09-17 15:11:51.902628Z] Finished install step (success) 08:11:51 INFO - [mozharness: 2016-09-17 15:11:51.902879Z] Running run-tests step. 08:11:51 INFO - Running pre-action listener: _resource_record_pre_action 08:11:51 INFO - Running main action method: run_tests 08:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:51 INFO - Python 2.7.3 08:11:51 INFO - Return code: 0 08:11:51 INFO - grabbing minidump binary from tooltool 08:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:51 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 0x196f790>, '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 0x1970690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1970b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:51 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:11:51 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:11:51 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:11:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:51 INFO - Return code: 0 08:11:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:11:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:11:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:51 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-017', '--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:11:51 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-017 --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:11:51 INFO - Using env: {'DISPLAY': ':0', 08:11:51 INFO - 'HOME': '/home/cltbld', 08:11:51 INFO - 'LANG': 'en_US.UTF-8', 08:11:51 INFO - 'LANGUAGE': 'en_US:en', 08:11:51 INFO - 'LOGNAME': 'cltbld', 08:11:51 INFO - 'MAIL': '/var/mail/cltbld', 08:11:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:51 INFO - 'MOZ_NO_REMOTE': '1', 08:11:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:51 INFO - 'NO_EM_RESTART': '1', 08:11:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:51 INFO - 'PWD': '/builds/slave/test', 08:11:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:51 INFO - 'SHELL': '/bin/bash', 08:11:51 INFO - 'SHLVL': '1', 08:11:51 INFO - 'TERM': 'linux', 08:11:51 INFO - 'TMOUT': '86400', 08:11:51 INFO - 'USER': 'cltbld', 08:11:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876', 08:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:51 INFO - '_': '/tools/buildbot/bin/python'} 08:11:51 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-017', '--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:11:52 INFO - mozversion application_buildid: 20160916163001 08:11:52 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:11:52 INFO - mozversion application_display_name: Nightly 08:11:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:52 INFO - mozversion application_name: Firefox 08:11:52 INFO - mozversion application_remotingname: firefox 08:11:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:11:52 INFO - mozversion application_vendor: Mozilla 08:11:52 INFO - mozversion application_version: 51.0a1 08:11:52 INFO - mozversion platform_buildid: 20160916163001 08:11:52 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:11:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:11:52 INFO - mozversion platform_version: 51.0a1 08:11:52 INFO - using testdate: 1474125112 08:11:52 INFO - actual date: 1474125112 08:11:52 INFO - starting webserver on 'localhost:57553' 08:11:52 INFO - SUITE-START | Running 2 tests 08:11:52 INFO - TEST-START | dromaeo_css 08:11:52 INFO - operating with platform_type : linux_ 08:11:52 INFO - Initialising browser for dromaeo_css test... 08:11:52 INFO - TEST-INFO | started process 4007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdNQhwt/profile http://localhost:57553/getInfo.html) 08:11:56 INFO - PROCESS | 4007 | __metrics Screen width/height:1600/1200 08:11:56 INFO - PROCESS | 4007 | colorDepth:24 08:11:56 INFO - PROCESS | 4007 | Browser inner width/height: 1024/697 08:11:56 INFO - PROCESS | 4007 | __metrics 08:11:56 INFO - PROCESS | 4007 | 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:11:56 INFO - PROCESS | 4007 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:57 INFO - PROCESS | 4007 | console.error: PushService: 08:11:57 INFO - PROCESS | 4007 | stateChangeProcessEnqueue: Error transitioning state 08:11:57 INFO - PROCESS | 4007 | UnknownError 08:11:57 INFO - TEST-INFO | 4007: exit 0 08:11:57 INFO - Browser initialized. 08:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:57 INFO - TEST-INFO | started process 4089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdNQhwt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:10 INFO - PROCESS | 4089 | RSS: Main: 172892160 08:12:10 INFO - PROCESS | 4089 | 08:12:28 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:12:33 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:12:59 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:13:01 INFO - PROCESS | 4089 | 1474125181514 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:04 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:13:10 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:13:13 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:13:22 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 08:13:30 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:13:39 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:13:44 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 08:13:46 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:13:48 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:13:58 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:14:04 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:14:06 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:08 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:16 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:14:32 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 08:14:48 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:14:57 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:15:06 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 08:15:11 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57553/tests/dromaeo/cssquery-ext.html) 08:15:12 INFO - PROCESS | 4089 | RSS: Main: 314429440 08:15:12 INFO - PROCESS | 4089 | 08:15:15 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.08343 seconds. 08:15:55 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:16:01 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:16:03 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:16:39 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:17:06 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:17:10 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01682 seconds. 08:17:12 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:17:20 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:17:22 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:17:25 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:17:38 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:40 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 08:17:42 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:17:58 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:18:10 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:18:12 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:14 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-ext.html (next: http://localhost:57553/tests/dromaeo/cssquery-jquery.html) 08:18:15 INFO - PROCESS | 4089 | RSS: Main: 277688320 08:18:15 INFO - PROCESS | 4089 | 08:18:21 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:18:25 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 08:18:39 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:18:58 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:19:01 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:19:08 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:19:20 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:19:22 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:19:30 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:19:40 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:50 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:19:52 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:19:54 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:20:03 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:20:11 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:20:15 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:20:21 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:20:33 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:45 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:49 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:20:55 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:56 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 2.01676 seconds. 08:21:01 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:09 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:11 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:15 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:17 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57553/tests/dromaeo/cssquery-mootools.html) 08:21:18 INFO - PROCESS | 4089 | RSS: Main: 267841536 08:21:18 INFO - PROCESS | 4089 | 08:21:21 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:21:23 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.03344 seconds. 08:21:28 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:21:30 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:38 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:21:50 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:52 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:56 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:04 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:22:05 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:22:07 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:22:09 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:22:23 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:22:33 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:22:35 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:55 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:22:57 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:23:05 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:07 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:23:28 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 08:23:31 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 08:23:40 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:23:41 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:23:53 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:24:00 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:24:02 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:24:06 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:24:14 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:18 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:24:20 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57553/tests/dromaeo/cssquery-prototype.html) 08:24:20 INFO - PROCESS | 4089 | RSS: Main: 216133632 08:24:20 INFO - PROCESS | 4089 | 08:24:24 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:24:29 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:37 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:24:45 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:09 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:25:11 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:25:22 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:25:28 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 08:25:30 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:25:42 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:25:46 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:25:54 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:26:05 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:26:12 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:26:15 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:26:16 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:26:18 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:26:24 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:26:29 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:26:41 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:26:43 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:23 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:27:23 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57553/tests/dromaeo/cssquery-yui.html) 08:27:24 INFO - PROCESS | 4089 | RSS: Main: 215797760 08:27:24 INFO - PROCESS | 4089 | 08:27:27 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:27:34 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:27:42 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:27:48 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:27:54 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 08:28:13 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:28:25 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 08:29:04 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:12 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:29:28 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:30 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:29:32 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:29:38 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:29:50 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:30:06 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:30:08 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:30:12 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:30:14 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:30:21 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:30:24 INFO - PROCESS | 4089 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:30:27 INFO - PROCESS | 4089 | Cycle 1(1): loaded http://localhost:57553/tests/dromaeo/cssquery-yui.html (next: http://localhost:57553/tests/dromaeo/cssquery-dojo.html) 08:30:27 INFO - PROCESS | 4089 | RSS: Main: 296263680 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | __start_tp_report 08:30:27 INFO - PROCESS | 4089 | _x_x_mozilla_page_load 08:30:27 INFO - PROCESS | 4089 | _x_x_mozilla_page_load_details 08:30:27 INFO - PROCESS | 4089 | |i|pagename|runs| 08:30:27 INFO - PROCESS | 4089 | |0;dojo.html;3011.728944395005;3036.787424880259;3045.802022868513;3075.5694202811596;3091.613548306462;5161.819336323229;5193.584513238941;5261.184516399954;5287.973560132199;5348.73256337183;5240.554552863011;5266.236395722627;5301.8939621207555;5312.123499622568;5315.787368505265;4722.669413141081;4761.166795810736;4788;4806.519348065201;4851.078295123915;4874.125070568906;4882.853514394574;4922.532359425867;4930.260460930871;4963.60291176705;4675.649326300541;4713.882152946169;4716.811327546894;4759.547842954914;4830.686005409637;4821.783019764119;4861.489543597907;4865.221564549655;4886.315915771795;4895.0454661341055;4791.856244312677;4793.832215872428;4793.856184314476;4804.1352556539805;4819.156647586658;5291.708956007456;5303.867403314909;5304.73476326186;5312.256284120226;5319.521243088141;5418.241446197536;5485.9725701371635;5556.388797232301;5619.129034999582;5620.325560932673;5637.633553818989;5654.264945557093;5658.773649054074;5663.207150998864;5729.942700573024;5269.578433725293;5274.92087618686;5279.736013199363;5280.551153151987;5345.1714984177515;5477.890442191134;5580.581456390787;5705.971470142623;5742.224799651994;5748.45389687979;5491.36849262332;5518.310211223597;5555.30558680165;5590.888182236333;5591.440855914376;5627.268455100892;5628.465295796893;5633;5633.943660563424;5639.802606908738;5368.32895888014;5417.4311697272005;5598.300212473441;5598.328200615871;5607.80372686954;5580.079286917613;5583.134614134816;5617.915731263953;5709.543236541067;5709.914351284734;4380.846670366522;4383.9342409863875;4389.1660584488845;4391.231534481517;4440.822367105279;3881.514810648669;3891.610838916086;3915.6084391560616;3960.405939109157;3970.5235371755575;5200.76596553156;5245.26566280721;5302.28419163408;5304.867378315495;5321.920171197357;5593.440655934455;5610.074337734228;5612.719364031782;5618.073017952074;5656.660600363992;5263.947360526422;5301.602379821452;5323.1216849219445;5326.786928522815;5387;4670.579647831643;4704.1062198182235;4810.8797280068275;4825.686330388514;4882.267659851051;5857.326407463196;5861.062230043172;5867.647941123631;5909.556783241188;5934.495567876693;5676.403977582295;5682.062459694104;5683.1759394886685;5683.77264909399;5685.971570142123;5488.341399032141;5607.635503692247;5789.45000224978;5841.8539536510225;5851.678157701367;5575;5588.6646801192055;5600.85997850057;5660.716964151694;5662.518685911744;5599.944000559943;5684;5686.2323586315315;5686.914696279642;5689.118186681071;5346.411894691659;5400.459954004568;5525.143602741581;5534.640248383842;5540.861478463071;5499.642523235977;5560.416156303691;5561.026820306511;5565.860853478695;5628.887422251614;5497.725113744217;5504.80733174337;5505.174223866452;5506.174073888948;5506.311711036121;5259.947400525945;5560.193771903132;5609.326880774333;5613.522850557748;5694.003549378925;5193.143131383431;5429.158480435539;5460.3993560707495;5493.423190564933;5577.944220557904 08:30:27 INFO - PROCESS | 4089 | |1;ext.html;555.1318317419555;581.444720292121;599.4455129005671;606.4753987800549;609.5154352289927;32535.511967320508;33197.33605327895;42714.78642606786;43614.34578481325;43659.563404366025;42129.36805947913;45708;45708.085838283216;45713.54286457135;45718.77140614292;8934.106589341063;9090.590923408463;9097.63609455621;9100.089991000914;9101.180893719564;8858.822823543525;8952.597133128993;8976.8653470198;8987.281017518615;8989;9045.59294831731;9067.274618030575;9072.501012444312;9073.183413492785;9079;9019.09809019096;9047.457152570867;9062.909370906273;9063.773405664879;9070.546472676406;8891.244244239248;8953.418027828171;8968.46189228642;8974;8977.281817454588;42217.78891105525;42388.364174537404;42481.57518424807;42520;42820.57179428197;3230.6769323067742;3302.78531895426;3322.1362445764034;3347.1464776482085;3410.5225268462436;32223;32450.350992980246;32457.513137302958;32617.673823261703;32894;4321.611055005033;4425.800838962255;4465.307877279027;4560.954390456086;4631.421072365954;233736;233791;233829;234253;234367;48418.27372589414;49411;55211.72394138084;55541.444585553625;55565.44434555683;51075;52129;52182.47817521852;52239.47760522346;52253.477465225624;15153.545393637987;15325.233738313264;15326.770098448533;15329.846701532842;15331.770023449657;11776.352300623377;11904.226225295328;11938.104642151873;11943.402829858473;11950.163488555718;16302;16308.266128024268;16347.509574712778;16366.672666546841;16376.508704738877;5421.430749771296;6384.52116091295;6385.8722825544155;6475.384838440341;6543.411093001558;906.419891269588;984.1142971325721;1060.3743791163251;1063.9414832184304;1077.380506208933;31365.37269254602;32107.839460803014;32279.51580726291;32327.51508727371;32344.606833577516;1040.573364920379;1046.3930920066343;1050.5587653185528;1114.420501339299;1164.8019836627793;17159.914200429168;17791.644167116585;17813.821861781475;18530.44408667742;18688.719669204973;503247;528628;534641;539109;835873;57887.421125788205;58229;58618.41381586215;58690.41309586935;58699.42355476223;1209.3046498263534;1244.3591550351757;1279.9104062715521;1309.4107651556646;1312.534050412109;29571.26071848135;33658.66341336555;33823.83088084544;33955.660443396235;34472.65527344795;53550.46449535611;54368.728156358964;54459;54551;54574.63563410788;466155;466265;467913;468605;469744;25576.360590985376;26199.345016374744;26432.207033789018;27483.725162748917;27754.5836812452;45400;45426.77286613678;45534.316985244586;46785.29822052604;46939.530604693515;38038.2392352157;38618.42072368861;39087.41368879412;40474;40501.709692254495;37516.81241593775;38149.80925095357;38425.80787096159;40436.1912761749;40455.79772101121;39097.804510977265;39302;39457.605423945395;39701.80149099236;39739.40390894081;31779;35613;36162;36563.817180913924;37709.622903770614;28028.29929251785;28216.71783282141;28221.576676350283;28235.858820705766;28465.28836779097 08:30:27 INFO - PROCESS | 4089 | |2;jquery.html;375.7125798763948;403.1392975996248;403.39607831448023;415.81080608323214;427.3802985670776;13259.27074010929;15187.088774673528;19077.713834292495;19102;19634.90182549085;18390.632187356277;18728.719069213974;18904.243830246774;18929.05354732265;19221.519462013457;1619.6922584708868;1619.7975253093364;1619.902805831654;1620.4490473239096;1836.4857839804843;4267.295896177127;4463.174312752135;4498.550144985508;5769.769209231626;5850.648961062328;6595.175603049619;6617.8014659560295;6724.293949135344;6727.024581435689;6732.697028633724;6473.385028422316;6608.570442921195;6641.867162656768;6647.833804154887;6714.529982901199;5656.547476201894;5820.534357251409;5873.442023007808;5877.441643043904;5879.882402351972;21238.787612123837;21981.450463738536;23179.188728394427;23551.29346119602;23607.29178124659;2086.0821238654944;2268.3875353654585;2268.943276418103;2390.756806460648;2869.6556413230337;9975.451350175737;10411.479426028744;11074.557017719282;11085.612003579916;12140.3322817245;1909.675355189621;1913.5981443896799;1957.9706304405445;1982.3854605072474;1993.5614164883702;76594;77142;78925;79477.99999999942;81321.59339203325;10628.574857005633;10732.570697172026;10744.409057501913;11265.324080555192;11411.942940285246;15843.049417035014;15893.443729469413;15925.761113583305;15935.442259520862;15940.362385504683;7893.407994400443;8012.238837310446;8137.633806478722;8335.458195217412;8355;7540.132884718328;7630.542167469969;7644.655990480442;7739.380849532024;7822.726204582926;4762.14281429346;4875.731834749052;4933.975330123399;5006.023825354094;5011.198208286657;1827.853771698261;2016.626924019061;2032.309014934929;2051.6819892916624;2054.3528788431895;912.771807048238;917.857732051533;918.554501066982;924.7456949338986;982.390917631059;17670.469885903185;19081.618367632847;19457.708134377997;19582.804171958098;19804.20783168686;933.3699752666922;958.1233171647864;962.9470379129075;1011.544804837826;1053.752368193474;10109.39343639384;10609.204309676805;10861.348319100722;10919.454027298603;10986.780264394667;428908;451342;453978;457320;461059.5357719559;94146;95886.52056739671;99158.5042074785;102603;103309;1019.1795604538418;1044.38903241603;1076.3972175581544;1080.330195278932;1080.4327727942737;17396.39112631052;17611.38360157388;17905.731414028538;18132.456026319232;18524.907375463037;41643;42157.57842421537;42757.57242427536;43026;43107.353389698554;59497.702511487165;60255.69872150611;60734;61249;62125.68937155285;8291.751247462586;8342.374321926001;8435;8590.484570925764;8795.296376289754;12193.451294691582;12706.745865082832;12745.553905613258;12827.551035713705;12978.351082446028;11105.833412498981;11107.888921111009;11216.943915280372;11409.771804564029;11592.942035289769;10860.67417977462;11007.284526505751;11095.944520277348;11196.776064478829;11277.887221127683;11138.721531961442;11394.202405831513;11397.658070257905;11466.426678665866;11914;8263.917360826315;8284.502929823988;8352.791180220544;8501.574921254036;8596.011458682082;8953.328500362368;11066.944665276622;11167.609133680282;11305.491252893804;11643.359615221245 08:30:27 INFO - PROCESS | 4089 | |3;mootools.html;1302.316283950925;1451.542764029331;1503.751880939645;1617.2156504095512;1774.520879362572;8131.67473301068;8925.464472131676;9145.725628231146;9236.722898313044;9477.383970041943;9292.210162136209;9327.720168394959;9339.252859771219;9347.158755711984;9350.719478415642;2407.6388541718707;2429.4898071405028;2433.3065076453213;2434.294054724128;2438.1222759806456;2339.4853132310864;2350.5298940212024;2394.964075538868;2397.160993652225;2397.304781613339;2350.106959355451;2367.562001029806;2369.341460977314;2406.4465173010217;2422.539717453687;2285.5771682238674;2303.665968434576;2312.2022902098665;2312.329424466911;2318.374039009458;2244.8989795459242;2257.3002369265582;2265.501589650274;2269.273832373641;2275.5790178816974;8850;9270.119338662817;9312.34813563051;9317.72046838596;9351.672691455766;5345.412004679482;5566.944330556683;5573.08044172711;5579.832605021856;5588.21764952907;3403.5915690117095;3414.146463384154;3476.3221171871496;3501.212227248874;3505.2989402119424;1116.1963386361888;1132.9546818127264;1156.4254135413096;1174.1428757007347;1174.4186627619383;13297.268650224234;13408.731825363535;13412.396442160127;13417.932910335483;13426.194428334331;4474.418325617714;4552.863414097583;4631.861044168681;4666.370040044619;4738.763061846894;10331.638392656221;10349.223808214414;10358.274920755475;10377.221708371902;10386.740331491623;5426.620136590401;5438.510534051875;5443.618946673695;5443.891122177534;5445.809396671177;4348.239058164809;4500.167469018242;4926.162552366106;5041.747912604355;5079.6952182869145;3903.6096390360735;3977.2840888639744;4046.59534046593;4088.5298190707854;4127.772972486482;1132.7111586545543;1149.316156886651;1152.8040233160382;1167.171308371049;1190.9047276217882;717.0248462091552;873.4977388001925;891.0688330694439;926.2451102351562;947.190152419685;6150.50795936324;6181.289151747516;6201.348858369896;6278.937210627927;6366.6180029198395;460.6913368043419;468.6742713813867;476.89508308172145;479.33851285226166;487.200990375784;4169.854055108117;4253.48958125027;4264.914701705949;4333.7833108345085;4352.194843953878;11403.372814495116;11799.52801887932;11839.408029598486;11869.169158158846;11897.40512974365;11718.121140914294;11764.235324704037;11780.70548236301;11817.822732659017;11829.763404731857;8188.426810123353;8572.571371431528;8583.742487725378;8671.956640216758;8745.650173993095;6238.126662267196;6299.653519056313;6405.295417503978;6471.7734879279;6474.158359413246;10858.45707714627;10952.890471095187;10962.506687198937;11243;11256.606018789304;10917.727056823644;11087.390193539433;11152.386618736047;11191.944040279746;11264.09887209005;8171.509709417454;8200.630971606412;8284.668613255402;8342.207490288412;8421.115782842959;8498.872516912128;8536.658533658832;8537.786555336166;8556;8563.015253245956;8175;8230.21812927771;8242.258196762321;8249.670013199644;8395.160483951557;7965.80085497844;8009.279164875189;8078.434509584273;8098.3116435103675;8117.1071182168735;7369.631518424165;7417.221191774786;7455.291747283999;7497.325240728361;7522.623868806647;5315.335583052119;5364.946350536445;5401.738731414447;5447.891042179055;5604.075327570905;5290.841274761763;5378.677279363251;5404.513593776579;5445.6732596042775;5491.423400543045 08:30:27 INFO - PROCESS | 4089 | |4;prototype.html;334.07294757049186;334.89450822990756;334.9480830471278;336.1428357687896;346.1950964008679;5266.683998960065;5282.784959459322;5317.9202311965355;5325.360956685194;5325.7869685212645;5206.479352064792;5219.747260657444;5277.6305658603915;5292.259083728281;5296.126139187029;3888.7472314299553;4006.519217693881;4073.40935564343;4174.791260436981;4180.770057646828;4127.153933443652;4132.814023368941;4199.811008504625;4218.535961044283;4219.810108545107;4103.076807718269;4108.5686002969705;4119.752814831119;4122.021429713739;4176.749395036308;4024.1750441159493;4101.589841015874;4103.302438585448;4111.198316328318;4130.731502452346;3626.6192049834654;3636.890893273206;3647.288778688157;3652.726045546595;3654.3422184006868;5074.31496747938;5098.694078355311;5105.897882042375;5130.820421285274;5160.225966105077;4502.842400515966;4671.813127474897;4715.009847931939;4719.882002949919;4731.290306454025;5449.509544141007;5449.782008719647;5462.8361149165585;5496.890062198773;5520.033994051025;4137.91724165518;4144.378343248507;4146.3158578834455;4147.834086636531;4148.688848336386;5540.703281547473;5615.494605485526;5653.547716182696;5801.767929282823;5828.766849326022;4156.168766246763;4191.350340697197;4198.6641068714425;4199.853005144805;4224.957750422518;4257.574242575718;4263.722858014219;4268.722533035343;4277;4278.251306021496;4417.712848664891;4789.928151077736;4792.95207047932;4808.687435316763;4809.591184749335;5401.405845357008;5419.485148910847;5421.918671219935;5432.69615292327;5461.617686761968;1427.571728481472;1444.2562080528537;1445.7831325301079;1450.3038541500139;1450.6953539756557;935.9423851048272;954.3987288008649;964.6382606522554;977.8217248215883;987.8195556310284;5052.686301561621;5181.663191892515;5324.201369794562;5338.332708411449;5342.54588359986;5603.355614104348;5618.381977982419;5639.3232812062815;5642.407547207484;5663.575231857627;5116.386033675983;5320.281761962163;5340.546053585411;5347.839564813062;5387.488188622075;2910.2869796899895;2984.7164519370626;2986.970130298713;2998.835064071497;3002.6997300270236;5837.854053648693;5848;5868.501177399969;5869.679322152567;5872.383399742966;5555.63825828061;5749.1663708762;5764.933487304778;5765.567582431335;5826.669332267043;5789.784145329444;5822.359540450547;5843.824685259364;5870.006296788754;5891.617044892068;5439.211314359475;5640.884668249407;5661.2923384576925;5700.429957004267;5701.40135285789;5590.9440905592055;5606.6636001840025;5611.971940140274;5622.24099746529;5647.519960803378;5797.4782269595935;5829.53363730892;5863.530917526673;5874.383189765013;5876.941230587639;3656.62876421342;3658.725595580289;3683.4290684943876;3757.8121093945742;3772.302124107049;4995.977433652733;5013.799448022039;5021.8362431669275;5088.676943994514;5119.948800511947;4942.4316203637045;4991.675541089818;4991.750412479434;5019.017488092256;5054.570361519165;4768.57082862544;4888.022395520981;4937.494064310276;4942.851714448572;4974.2890124881305;4836.854894353175;4838.637102217277;4853.781579828987;4863;4878.390201224847;5335;5399.973000134974;5454.836354909359;5499.587530935116;5526.972365138309;5395.843431592562;5402.216678581505;5403.108487099742;5435.429279925709;5438.972805135949 08:30:27 INFO - PROCESS | 4089 | |5;yui.html;759.787259567321;776.6272189349112;787.5589669784921;793.3098204562032;804.19178725381;13866.098703584272;14120.858791412084;14179.432822687106;14234.43062277509;14258.429662813487;13835.654108647288;14309.78535321971;14350.138991660508;15017;15133.09201447911;791.244361634639;809.2393150438598;813.4590497319275;813.7680760983121;813.9186081391844;651.8207492939432;673.85848971716;805.8509175802466;807.9192080791903;808.4340961327065;817.6606708216113;844.7085755414372;855.3200205836375;856.6059612578222;857.1557016338901;842.9957850210741;843.068409407608;843.6541018182515;844.1431946574232;844.1980118887082;380.3628921556382;388.35533015194926;619.8357435279654;620.3951147631069;620.8168590265882;14143.646408839759;14682.779758303634;14772.187529685863;15112.848871511364;15245.085294882343;14884.255787210595;15252.23738813066;15299.617509562238;15804.367825286974;15831.208439577975;11795.292282462993;11846.407679615984;12072.456739446747;12234.632961011095;13072.281024543647;3899.980500097481;3954.189391174802;4084.1219137885496;4117.91764164718;4118.608732170439;77867.22132778657;78928.21071789267;79309;79974;80204.59897700533;10831.566737330577;11487.138464615187;11550.711232219262;11862.644120676394;12073.57742479009;21613.891930540245;21897.781022189574;21911.342659720234;21946.89026554857;21950.231741889278;7881.5665138417935;8506.532140732319;8569.442986206;8672.046074931752;8682.565871706389;4320.697551171388;4487.798049087799;4501.122281155143;4525.502194758574;4700.788464519036;3625.9818700906853;3770.8868733938025;3802.752821066622;3803.448499967489;3812.256609961031;592.9021711417654;597.5816928150313;599.3407252022827;607.902735562308;629.3046183966742;674.3728332650584;692.7852365766629;699.2832346844424;709.6168069242551;718.1023720349563;9289.953550232342;9638.303357195247;10123.797524049478;10382.480875956171;10445.425501597334;547.4689551135392;602.5661523702927;644.5488158289216;717.3866344275674;719.8884172953201;4447.644188464915;4498.775061246924;4847.224444088929;4894.452821204658;4952.826651067267;115307.84692153138;124106;124431.37784311021;126025;129535;64275.678621606596;82501.58749206216;98341.5082924595;106304;112908;79228.20771792323;82655.586722066;88882.55558722165;89792.5510372444;92991;9452.95273523628;9559.474228917197;9568.712938611852;9989.700308990741;10211.438370889375;52136.77757276365;52986;53252.201216981004;54132.18801718056;54955;61838.69080654568;63114.68442657757;63399.36600634119;76867.61566192357;80927.19072809197;13299.734005319646;13504.86495135036;13550.79673804874;13634.454621815414;13641.24973126448;14318.14091154534;14758.704825903207;15147.54557363281;15583;15800.76298855495;13514.459421623025;13769.518066868011;14087;14183.929080354945;14217.146971181763;14039.298035098;14492.782608260673;14704.347696451683;14715.190664513126;15344.693106138038;12528.498860045496;13149.934250328688;13477.393517291941;13617.591472255848;14041.789373159616;11103.278286911325;11182.66452006441;11223.326600404002;11603.6518904433;11624.18630695877;11348.319100853974;11519.202831625327;11662.241954272946;11759.176857620227;11868.287902725864 08:30:27 INFO - PROCESS | 4089 | __end_tp_report 08:30:27 INFO - PROCESS | 4089 | __start_cc_report 08:30:27 INFO - PROCESS | 4089 | _x_x_mozilla_cycle_collect,1074 08:30:27 INFO - PROCESS | 4089 | __end_cc_report 08:30:27 INFO - PROCESS | 4089 | __startTimestamp1474126227643__endTimestamp 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | ------- Summary: start ------- 08:30:27 INFO - PROCESS | 4089 | Number of tests: 6 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#0] dojo.html Cycles:165 Average:5239.16 Median:5429.16 stddev:581.25 (10.7%) stddev-sans-first:556.15 08:30:27 INFO - PROCESS | 4089 | Values: 3011.7 3036.8 3045.8 3075.6 3091.6 5161.8 5193.6 5261.2 5288.0 5348.7 5240.6 5266.2 5301.9 5312.1 5315.8 4722.7 4761.2 4788.0 4806.5 4851.1 4874.1 4882.9 4922.5 4930.3 4963.6 4675.6 4713.9 4716.8 4759.5 4830.7 4821.8 4861.5 4865.2 4886.3 4895.0 4791.9 4793.8 4793.9 4804.1 4819.2 5291.7 5303.9 5304.7 5312.3 5319.5 5418.2 5486.0 5556.4 5619.1 5620.3 5637.6 5654.3 5658.8 5663.2 5729.9 5269.6 5274.9 5279.7 5280.6 5345.2 5477.9 5580.6 5706.0 5742.2 5748.5 5491.4 5518.3 5555.3 5590.9 5591.4 5627.3 5628.5 5633.0 5633.9 5639.8 5368.3 5417.4 5598.3 5598.3 5607.8 5580.1 5583.1 5617.9 5709.5 5709.9 4380.8 4383.9 4389.2 4391.2 4440.8 3881.5 3891.6 3915.6 3960.4 3970.5 5200.8 5245.3 5302.3 5304.9 5321.9 5593.4 5610.1 5612.7 5618.1 5656.7 5263.9 5301.6 5323.1 5326.8 5387.0 4670.6 4704.1 4810.9 4825.7 4882.3 5857.3 5861.1 5867.6 5909.6 5934.5 5676.4 5682.1 5683.2 5683.8 5686.0 5488.3 5607.6 5789.5 5841.9 5851.7 5575.0 5588.7 5600.9 5660.7 5662.5 5599.9 5684.0 5686.2 5686.9 5689.1 5346.4 5400.5 5525.1 5534.6 5540.9 5499.6 5560.4 5561.0 5565.9 5628.9 5497.7 5504.8 5505.2 5506.2 5506.3 5259.9 5560.2 5609.3 5613.5 5694.0 5193.1 5429.2 5460.4 5493.4 5577.9 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#1] ext.html Cycles:180 Average:58634.35 Median:42173.58 stddev:123128.96 (292.0%) stddev-sans-first:123397.15 08:30:27 INFO - PROCESS | 4089 | Values: 555.1 581.4 599.4 606.5 609.5 32535.5 33197.3 42714.8 43614.3 43659.6 42129.4 45708.0 45708.1 45713.5 45718.8 8934.1 9090.6 9097.6 9100.1 9101.2 8858.8 8952.6 8976.9 8987.3 8989.0 9045.6 9067.3 9072.5 9073.2 9079.0 9019.1 9047.5 9062.9 9063.8 9070.5 8891.2 8953.4 8968.5 8974.0 8977.3 42217.8 42388.4 42481.6 42520.0 42820.6 3230.7 3302.8 3322.1 3347.1 3410.5 32223.0 32450.4 32457.5 32617.7 32894.0 4321.6 4425.8 4465.3 4561.0 4631.4 233736.0 233791.0 233829.0 234253.0 234367.0 48418.3 49411.0 55211.7 55541.4 55565.4 51075.0 52129.0 52182.5 52239.5 52253.5 15153.5 15325.2 15326.8 15329.8 15331.8 11776.4 11904.2 11938.1 11943.4 11950.2 16302.0 16308.3 16347.5 16366.7 16376.5 5421.4 6384.5 6385.9 6475.4 6543.4 906.4 984.1 1060.4 1063.9 1077.4 31365.4 32107.8 32279.5 32327.5 32344.6 1040.6 1046.4 1050.6 1114.4 1164.8 17159.9 17791.6 17813.8 18530.4 18688.7 503247.0 528628.0 534641.0 539109.0 835873.0 57887.4 58229.0 58618.4 58690.4 58699.4 1209.3 1244.4 1279.9 1309.4 1312.5 29571.3 33658.7 33823.8 33955.7 34472.7 53550.5 54368.7 54459.0 54551.0 54574.6 466155.0 466265.0 467913.0 468605.0 469744.0 25576.4 26199.3 26432.2 27483.7 27754.6 45400.0 45426.8 45534.3 46785.3 46939.5 38038.2 38618.4 39087.4 40474.0 40501.7 37516.8 38149.8 38425.8 40436.2 40455.8 39097.8 39302.0 39457.6 39701.8 39739.4 31779.0 35613.0 36162.0 36563.8 37709.6 28028.3 28216.7 28221.6 28235.9 28465.3 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#2] jquery.html Cycles:180 Average:27953.27 Median:2268.67 stddev:74757.61 (3295.2%) stddev-sans-first:74938.65 08:30:27 INFO - PROCESS | 4089 | Values: 375.7 403.1 403.4 415.8 427.4 13259.3 15187.1 19077.7 19102.0 19634.9 18390.6 18728.7 18904.2 18929.1 19221.5 1619.7 1619.8 1619.9 1620.4 1836.5 4267.3 4463.2 4498.6 5769.8 5850.6 6595.2 6617.8 6724.3 6727.0 6732.7 6473.4 6608.6 6641.9 6647.8 6714.5 5656.5 5820.5 5873.4 5877.4 5879.9 21238.8 21981.5 23179.2 23551.3 23607.3 2086.1 2268.4 2268.9 2390.8 2869.7 9975.5 10411.5 11074.6 11085.6 12140.3 1909.7 1913.6 1958.0 1982.4 1993.6 76594.0 77142.0 78925.0 79478.0 81321.6 10628.6 10732.6 10744.4 11265.3 11411.9 15843.0 15893.4 15925.8 15935.4 15940.4 7893.4 8012.2 8137.6 8335.5 8355.0 7540.1 7630.5 7644.7 7739.4 7822.7 4762.1 4875.7 4934.0 5006.0 5011.2 1827.9 2016.6 2032.3 2051.7 2054.4 912.8 917.9 918.6 924.7 982.4 17670.5 19081.6 19457.7 19582.8 19804.2 933.4 958.1 962.9 1011.5 1053.8 10109.4 10609.2 10861.3 10919.5 10986.8 428908.0 451342.0 453978.0 457320.0 461059.5 94146.0 95886.5 99158.5 102603.0 103309.0 1019.2 1044.4 1076.4 1080.3 1080.4 17396.4 17611.4 17905.7 18132.5 18524.9 41643.0 42157.6 42757.6 43026.0 43107.4 59497.7 60255.7 60734.0 61249.0 62125.7 8291.8 8342.4 8435.0 8590.5 8795.3 12193.5 12706.7 12745.6 12827.6 12978.4 11105.8 11107.9 11216.9 11409.8 11592.9 10860.7 11007.3 11095.9 11196.8 11277.9 11138.7 11394.2 11397.7 11466.4 11914.0 8263.9 8284.5 8352.8 8501.6 8596.0 8953.3 11066.9 11167.6 11305.5 11643.4 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#3] mootools.html Cycles:180 Average:6073.87 Median:4649.12 stddev:3588.20 (77.2%) stddev-sans-first:3580.35 08:30:27 INFO - PROCESS | 4089 | Values: 1302.3 1451.5 1503.8 1617.2 1774.5 8131.7 8925.5 9145.7 9236.7 9477.4 9292.2 9327.7 9339.3 9347.2 9350.7 2407.6 2429.5 2433.3 2434.3 2438.1 2339.5 2350.5 2395.0 2397.2 2397.3 2350.1 2367.6 2369.3 2406.4 2422.5 2285.6 2303.7 2312.2 2312.3 2318.4 2244.9 2257.3 2265.5 2269.3 2275.6 8850.0 9270.1 9312.3 9317.7 9351.7 5345.4 5566.9 5573.1 5579.8 5588.2 3403.6 3414.1 3476.3 3501.2 3505.3 1116.2 1133.0 1156.4 1174.1 1174.4 13297.3 13408.7 13412.4 13417.9 13426.2 4474.4 4552.9 4631.9 4666.4 4738.8 10331.6 10349.2 10358.3 10377.2 10386.7 5426.6 5438.5 5443.6 5443.9 5445.8 4348.2 4500.2 4926.2 5041.7 5079.7 3903.6 3977.3 4046.6 4088.5 4127.8 1132.7 1149.3 1152.8 1167.2 1190.9 717.0 873.5 891.1 926.2 947.2 6150.5 6181.3 6201.3 6278.9 6366.6 460.7 468.7 476.9 479.3 487.2 4169.9 4253.5 4264.9 4333.8 4352.2 11403.4 11799.5 11839.4 11869.2 11897.4 11718.1 11764.2 11780.7 11817.8 11829.8 8188.4 8572.6 8583.7 8672.0 8745.7 6238.1 6299.7 6405.3 6471.8 6474.2 10858.5 10952.9 10962.5 11243.0 11256.6 10917.7 11087.4 11152.4 11191.9 11264.1 8171.5 8200.6 8284.7 8342.2 8421.1 8498.9 8536.7 8537.8 8556.0 8563.0 8175.0 8230.2 8242.3 8249.7 8395.2 7965.8 8009.3 8078.4 8098.3 8117.1 7369.6 7417.2 7455.3 7497.3 7522.6 5315.3 5364.9 5401.7 5447.9 5604.1 5290.8 5378.7 5404.5 5445.7 5491.4 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#4] prototype.html Cycles:180 Average:4588.28 Median:5093.69 stddev:1326.90 (26.0%) stddev-sans-first:1291.63 08:30:27 INFO - PROCESS | 4089 | Values: 334.1 334.9 334.9 336.1 346.2 5266.7 5282.8 5317.9 5325.4 5325.8 5206.5 5219.7 5277.6 5292.3 5296.1 3888.7 4006.5 4073.4 4174.8 4180.8 4127.2 4132.8 4199.8 4218.5 4219.8 4103.1 4108.6 4119.8 4122.0 4176.7 4024.2 4101.6 4103.3 4111.2 4130.7 3626.6 3636.9 3647.3 3652.7 3654.3 5074.3 5098.7 5105.9 5130.8 5160.2 4502.8 4671.8 4715.0 4719.9 4731.3 5449.5 5449.8 5462.8 5496.9 5520.0 4137.9 4144.4 4146.3 4147.8 4148.7 5540.7 5615.5 5653.5 5801.8 5828.8 4156.2 4191.4 4198.7 4199.9 4225.0 4257.6 4263.7 4268.7 4277.0 4278.3 4417.7 4789.9 4793.0 4808.7 4809.6 5401.4 5419.5 5421.9 5432.7 5461.6 1427.6 1444.3 1445.8 1450.3 1450.7 935.9 954.4 964.6 977.8 987.8 5052.7 5181.7 5324.2 5338.3 5342.5 5603.4 5618.4 5639.3 5642.4 5663.6 5116.4 5320.3 5340.5 5347.8 5387.5 2910.3 2984.7 2987.0 2998.8 3002.7 5837.9 5848.0 5868.5 5869.7 5872.4 5555.6 5749.2 5764.9 5765.6 5826.7 5789.8 5822.4 5843.8 5870.0 5891.6 5439.2 5640.9 5661.3 5700.4 5701.4 5590.9 5606.7 5612.0 5622.2 5647.5 5797.5 5829.5 5863.5 5874.4 5876.9 3656.6 3658.7 3683.4 3757.8 3772.3 4996.0 5013.8 5021.8 5088.7 5119.9 4942.4 4991.7 4991.8 5019.0 5054.6 4768.6 4888.0 4937.5 4942.9 4974.3 4836.9 4838.6 4853.8 4863.0 4878.4 5335.0 5400.0 5454.8 5499.6 5527.0 5395.8 5402.2 5403.1 5435.4 5439.0 08:30:27 INFO - PROCESS | 4089 | 08:30:27 INFO - PROCESS | 4089 | [#5] yui.html Cycles:180 Average:20979.78 Median:4101.02 stddev:30470.43 (743.0%) stddev-sans-first:30518.08 08:30:27 INFO - PROCESS | 4089 | Values: 759.8 776.6 787.6 793.3 804.2 13866.1 14120.9 14179.4 14234.4 14258.4 13835.7 14309.8 14350.1 15017.0 15133.1 791.2 809.2 813.5 813.8 813.9 651.8 673.9 805.9 807.9 808.4 817.7 844.7 855.3 856.6 857.2 843.0 843.1 843.7 844.1 844.2 380.4 388.4 619.8 620.4 620.8 14143.6 14682.8 14772.2 15112.8 15245.1 14884.3 15252.2 15299.6 15804.4 15831.2 11795.3 11846.4 12072.5 12234.6 13072.3 3900.0 3954.2 4084.1 4117.9 4118.6 77867.2 78928.2 79309.0 79974.0 80204.6 10831.6 11487.1 11550.7 11862.6 12073.6 21613.9 21897.8 21911.3 21946.9 21950.2 7881.6 8506.5 8569.4 8672.0 8682.6 4320.7 4487.8 4501.1 4525.5 4700.8 3626.0 3770.9 3802.8 3803.4 3812.3 592.9 597.6 599.3 607.9 629.3 674.4 692.8 699.3 709.6 718.1 9290.0 9638.3 10123.8 10382.5 10445.4 547.5 602.6 644.5 717.4 719.9 4447.6 4498.8 4847.2 4894.5 4952.8 115307.8 124106.0 124431.4 126025.0 129535.0 64275.7 82501.6 98341.5 106304.0 112908.0 79228.2 82655.6 88882.6 89792.6 92991.0 9453.0 9559.5 9568.7 9989.7 10211.4 52136.8 52986.0 53252.2 54132.2 54955.0 61838.7 63114.7 63399.4 76867.6 80927.2 13299.7 13504.9 13550.8 13634.5 13641.2 14318.1 14758.7 15147.5 15583.0 15800.8 13514.5 13769.5 14087.0 14183.9 14217.1 14039.3 14492.8 14704.3 14715.2 15344.7 12528.5 13149.9 13477.4 13617.6 14041.8 11103.3 11182.7 11223.3 11603.7 11624.2 11348.3 11519.2 11662.2 11759.2 11868.3 08:30:27 INFO - PROCESS | 4089 | -------- Summary: end -------- 08:30:27 INFO - PROCESS | 4089 | 08:30:28 INFO - TEST-INFO | 4089: exit 0 08:30:28 INFO - TEST-OK | dromaeo_css | took 1116352ms 08:30:28 INFO - TEST-START | kraken 08:30:28 INFO - operating with platform_type : linux_ 08:30:28 INFO - Initialising browser for kraken test... 08:30:28 INFO - TEST-INFO | started process 4402 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpatc13B/profile http://localhost:57553/getInfo.html) 08:30:32 INFO - PROCESS | 4402 | __metrics Screen width/height:1600/1200 08:30:32 INFO - PROCESS | 4402 | colorDepth:24 08:30:32 INFO - PROCESS | 4402 | Browser inner width/height: 1024/697 08:30:32 INFO - PROCESS | 4402 | __metrics 08:30:33 INFO - PROCESS | 4402 | 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:30:33 INFO - PROCESS | 4402 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:33 INFO - PROCESS | 4402 | console.error: PushService: 08:30:33 INFO - PROCESS | 4402 | stateChangeProcessEnqueue: Error transitioning state 08:30:33 INFO - PROCESS | 4402 | UnknownError 08:30:34 INFO - TEST-INFO | 4402: exit 0 08:30:34 INFO - Browser initialized. 08:30:34 INFO - Running cycle 1/1 for kraken test... 08:30:34 INFO - TEST-INFO | started process 4483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpatc13B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:46 INFO - PROCESS | 4483 | RSS: Main: 173002752 08:30:46 INFO - PROCESS | 4483 | 08:30:48 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57553/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:49 INFO - PROCESS | 4483 | RSS: Main: 241340416 08:30:49 INFO - PROCESS | 4483 | 08:30:52 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57553/tests/kraken/driver.html?testName=audio-dft) 08:30:52 INFO - PROCESS | 4483 | RSS: Main: 216682496 08:30:52 INFO - PROCESS | 4483 | 08:30:56 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57553/tests/kraken/driver.html?testName=audio-fft) 08:30:56 INFO - PROCESS | 4483 | RSS: Main: 298102784 08:30:56 INFO - PROCESS | 4483 | 08:30:58 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57553/tests/kraken/driver.html?testName=audio-oscillator) 08:30:59 INFO - PROCESS | 4483 | RSS: Main: 207679488 08:30:59 INFO - PROCESS | 4483 | 08:31:01 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:02 INFO - PROCESS | 4483 | RSS: Main: 211812352 08:31:02 INFO - PROCESS | 4483 | 08:31:05 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57553/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:05 INFO - PROCESS | 4483 | RSS: Main: 248573952 08:31:05 INFO - PROCESS | 4483 | 08:31:09 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57553/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:09 INFO - PROCESS | 4483 | RSS: Main: 247398400 08:31:09 INFO - PROCESS | 4483 | 08:31:12 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57553/tests/kraken/driver.html?testName=json-parse-financial) 08:31:13 INFO - PROCESS | 4483 | RSS: Main: 247508992 08:31:13 INFO - PROCESS | 4483 | 08:31:14 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:15 INFO - PROCESS | 4483 | RSS: Main: 209649664 08:31:15 INFO - PROCESS | 4483 | 08:31:16 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:17 INFO - PROCESS | 4483 | RSS: Main: 216592384 08:31:17 INFO - PROCESS | 4483 | 08:31:19 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:20 INFO - PROCESS | 4483 | RSS: Main: 221388800 08:31:20 INFO - PROCESS | 4483 | 08:31:22 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:31:23 INFO - PROCESS | 4483 | RSS: Main: 222584832 08:31:23 INFO - PROCESS | 4483 | 08:31:26 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:26 INFO - PROCESS | 4483 | RSS: Main: 220872704 08:31:26 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | Cycle 1(1): loaded http://localhost:57553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57553/tests/kraken/driver.html?testName=ai-astar) 08:31:28 INFO - PROCESS | 4483 | RSS: Main: 271962112 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | __start_tp_report 08:31:28 INFO - PROCESS | 4483 | _x_x_mozilla_page_load 08:31:28 INFO - PROCESS | 4483 | _x_x_mozilla_page_load_details 08:31:28 INFO - PROCESS | 4483 | |i|pagename|runs| 08:31:28 INFO - PROCESS | 4483 | |0;ai-astar;109;113;110;112;109;111;110;110;110;109 08:31:28 INFO - PROCESS | 4483 | |1;audio-beat-detection;151;119;122;161;120;127;124;124;153;126 08:31:28 INFO - PROCESS | 4483 | |2;audio-dft;162;160;161;162;161;164;162;163;163;163 08:31:28 INFO - PROCESS | 4483 | |3;audio-fft;75;75;76;114;76;75;76;75;99;75 08:31:28 INFO - PROCESS | 4483 | |4;audio-oscillator;85;83;123;82;83;82;102;82;83;111 08:31:28 INFO - PROCESS | 4483 | |5;imaging-gaussian-blur;118;118;117;117;116;117;117;117;117;117 08:31:28 INFO - PROCESS | 4483 | |6;imaging-darkroom;146;146;145;145;146;145;146;144;146;145 08:31:28 INFO - PROCESS | 4483 | |7;imaging-desaturate;96;96;96;96;94;95;95;95;95;95 08:31:28 INFO - PROCESS | 4483 | |8;json-parse-financial;51;50;49;50;50;51;51;50;51;51 08:31:28 INFO - PROCESS | 4483 | |9;json-stringify-tinderbox;60;60;60;60;60;59;60;60;60;59 08:31:28 INFO - PROCESS | 4483 | |10;stanford-crypto-aes;78;79;79;79;81;84;78;84;79;79 08:31:28 INFO - PROCESS | 4483 | |11;stanford-crypto-ccm;125;125;123;125;133;125;126;134;169;139 08:31:28 INFO - PROCESS | 4483 | |12;stanford-crypto-pbkdf2;169;164;165;171;164;170;167;169;166;168 08:31:28 INFO - PROCESS | 4483 | |13;stanford-crypto-sha256-iterative;67;67;65;67;67;66;66;65;66;65 08:31:28 INFO - PROCESS | 4483 | __end_tp_report 08:31:28 INFO - PROCESS | 4483 | __start_cc_report 08:31:28 INFO - PROCESS | 4483 | _x_x_mozilla_cycle_collect,1651 08:31:28 INFO - PROCESS | 4483 | __end_cc_report 08:31:28 INFO - PROCESS | 4483 | __startTimestamp1474126288714__endTimestamp 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | ------- Summary: start ------- 08:31:28 INFO - PROCESS | 4483 | Number of tests: 14 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:1.34 (1.2%) stddev-sans-first:1.33 08:31:28 INFO - PROCESS | 4483 | Values: 109.0 113.0 110.0 112.0 109.0 111.0 110.0 110.0 110.0 109.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:126.50 stddev:15.78 (12.5%) stddev-sans-first:15.28 08:31:28 INFO - PROCESS | 4483 | Values: 151.0 119.0 122.0 161.0 120.0 127.0 124.0 124.0 153.0 126.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 08:31:28 INFO - PROCESS | 4483 | Values: 162.0 160.0 161.0 162.0 161.0 164.0 162.0 163.0 163.0 163.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.50 stddev:13.60 (18.0%) stddev-sans-first:14.21 08:31:28 INFO - PROCESS | 4483 | Values: 75.0 75.0 76.0 114.0 76.0 75.0 76.0 75.0 99.0 75.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:82.50 stddev:14.95 (18.1%) stddev-sans-first:15.67 08:31:28 INFO - PROCESS | 4483 | Values: 85.0 83.0 123.0 82.0 83.0 82.0 102.0 82.0 83.0 111.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 08:31:28 INFO - PROCESS | 4483 | Values: 118.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 08:31:28 INFO - PROCESS | 4483 | Values: 146.0 146.0 145.0 145.0 146.0 145.0 146.0 144.0 146.0 145.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 08:31:28 INFO - PROCESS | 4483 | Values: 96.0 96.0 96.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:0.70 (1.4%) stddev-sans-first:0.71 08:31:28 INFO - PROCESS | 4483 | Values: 51.0 50.0 49.0 50.0 50.0 51.0 51.0 50.0 51.0 51.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 08:31:28 INFO - PROCESS | 4483 | Values: 60.0 60.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 59.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.00 stddev:2.26 (2.9%) stddev-sans-first:2.28 08:31:28 INFO - PROCESS | 4483 | Values: 78.0 79.0 79.0 79.0 81.0 84.0 78.0 84.0 79.0 79.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#11] stanford-crypto-ccm Cycles:10 Average:132.40 Median:129.50 stddev:13.88 (10.7%) stddev-sans-first:14.46 08:31:28 INFO - PROCESS | 4483 | Values: 125.0 125.0 123.0 125.0 133.0 125.0 126.0 134.0 169.0 139.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.50 stddev:2.50 (1.5%) stddev-sans-first:2.57 08:31:28 INFO - PROCESS | 4483 | Values: 169.0 164.0 165.0 171.0 164.0 170.0 167.0 169.0 166.0 168.0 08:31:28 INFO - PROCESS | 4483 | 08:31:28 INFO - PROCESS | 4483 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 08:31:28 INFO - PROCESS | 4483 | Values: 67.0 67.0 65.0 67.0 67.0 66.0 66.0 65.0 66.0 65.0 08:31:28 INFO - PROCESS | 4483 | -------- Summary: end -------- 08:31:28 INFO - PROCESS | 4483 | 08:31:30 INFO - TEST-INFO | 4483: exit 0 08:31:30 INFO - TEST-OK | kraken | took 61548ms 08:31:30 INFO - SUITE-END | took 1177s 08:31:30 INFO - Completed test suite (00:19:38) 08:31:30 INFO - javascript benchmark 08:31:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.728944395005, 3036.787424880259, 3045.802022868513, 3075.5694202811596, 3091.613548306462, 5161.819336323229, 5193.584513238941, 5261.184516399954, 5287.973560132199, 5348.73256337183, 5240.554552863011, 5266.236395722627, 5301.8939621207555, 5312.123499622568, 5315.787368505265, 4722.669413141081, 4761.166795810736, 4788.0, 4806.519348065201, 4851.078295123915, 4874.125070568906, 4882.853514394574, 4922.532359425867, 4930.260460930871, 4963.60291176705, 4675.649326300541, 4713.882152946169, 4716.811327546894, 4759.547842954914, 4830.686005409637, 4821.783019764119, 4861.489543597907, 4865.221564549655, 4886.315915771795, 4895.0454661341055, 4791.856244312677, 4793.832215872428, 4793.856184314476, 4804.1352556539805, 4819.156647586658, 5291.708956007456, 5303.867403314909, 5304.73476326186, 5312.256284120226, 5319.521243088141, 5418.241446197536, 5485.9725701371635, 5556.388797232301, 5619.129034999582, 5620.325560932673, 5637.633553818989, 5654.264945557093, 5658.773649054074, 5663.207150998864, 5729.942700573024, 5269.578433725293, 5274.92087618686, 5279.736013199363, 5280.551153151987, 5345.1714984177515, 5477.890442191134, 5580.581456390787, 5705.971470142623, 5742.224799651994, 5748.45389687979, 5491.36849262332, 5518.310211223597, 5555.30558680165, 5590.888182236333, 5591.440855914376, 5627.268455100892, 5628.465295796893, 5633.0, 5633.943660563424, 5639.802606908738, 5368.32895888014, 5417.4311697272005, 5598.300212473441, 5598.328200615871, 5607.80372686954, 5580.079286917613, 5583.134614134816, 5617.915731263953, 5709.543236541067, 5709.914351284734, 4380.846670366522, 4383.9342409863875, 4389.1660584488845, 4391.231534481517, 4440.822367105279, 3881.514810648669, 3891.610838916086, 3915.6084391560616, 3960.405939109157, 3970.5235371755575, 5200.76596553156, 5245.26566280721, 5302.28419163408, 5304.867378315495, 5321.920171197357, 5593.440655934455, 5610.074337734228, 5612.719364031782, 5618.073017952074, 5656.660600363992, 5263.947360526422, 5301.602379821452, 5323.1216849219445, 5326.786928522815, 5387.0, 4670.579647831643, 4704.1062198182235, 4810.8797280068275, 4825.686330388514, 4882.267659851051, 5857.326407463196, 5861.062230043172, 5867.647941123631, 5909.556783241188, 5934.495567876693, 5676.403977582295, 5682.062459694104, 5683.1759394886685, 5683.77264909399, 5685.971570142123, 5488.341399032141, 5607.635503692247, 5789.45000224978, 5841.8539536510225, 5851.678157701367, 5575.0, 5588.6646801192055, 5600.85997850057, 5660.716964151694, 5662.518685911744, 5599.944000559943, 5684.0, 5686.2323586315315, 5686.914696279642, 5689.118186681071, 5346.411894691659, 5400.459954004568, 5525.143602741581, 5534.640248383842, 5540.861478463071, 5499.642523235977, 5560.416156303691, 5561.026820306511, 5565.860853478695, 5628.887422251614, 5497.725113744217, 5504.80733174337, 5505.174223866452, 5506.174073888948, 5506.311711036121, 5259.947400525945, 5560.193771903132, 5609.326880774333, 5613.522850557748, 5694.003549378925, 5193.143131383431, 5429.158480435539, 5460.3993560707495, 5493.423190564933, 5577.944220557904], "value": 5200.87538338709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.1318317419555, 581.444720292121, 599.4455129005671, 606.4753987800549, 609.5154352289927, 32535.511967320508, 33197.33605327895, 42714.78642606786, 43614.34578481325, 43659.563404366025, 42129.36805947913, 45708.0, 45708.085838283216, 45713.54286457135, 45718.77140614292, 8934.106589341063, 9090.590923408463, 9097.63609455621, 9100.089991000914, 9101.180893719564, 8858.822823543525, 8952.597133128993, 8976.8653470198, 8987.281017518615, 8989.0, 9045.59294831731, 9067.274618030575, 9072.501012444312, 9073.183413492785, 9079.0, 9019.09809019096, 9047.457152570867, 9062.909370906273, 9063.773405664879, 9070.546472676406, 8891.244244239248, 8953.418027828171, 8968.46189228642, 8974.0, 8977.281817454588, 42217.78891105525, 42388.364174537404, 42481.57518424807, 42520.0, 42820.57179428197, 3230.6769323067742, 3302.78531895426, 3322.1362445764034, 3347.1464776482085, 3410.5225268462436, 32223.0, 32450.350992980246, 32457.513137302958, 32617.673823261703, 32894.0, 4321.611055005033, 4425.800838962255, 4465.307877279027, 4560.954390456086, 4631.421072365954, 233736.0, 233791.0, 233829.0, 234253.0, 234367.0, 48418.27372589414, 49411.0, 55211.72394138084, 55541.444585553625, 55565.44434555683, 51075.0, 52129.0, 52182.47817521852, 52239.47760522346, 52253.477465225624, 15153.545393637987, 15325.233738313264, 15326.770098448533, 15329.846701532842, 15331.770023449657, 11776.352300623377, 11904.226225295328, 11938.104642151873, 11943.402829858473, 11950.163488555718, 16302.0, 16308.266128024268, 16347.509574712778, 16366.672666546841, 16376.508704738877, 5421.430749771296, 6384.52116091295, 6385.8722825544155, 6475.384838440341, 6543.411093001558, 906.419891269588, 984.1142971325721, 1060.3743791163251, 1063.9414832184304, 1077.380506208933, 31365.37269254602, 32107.839460803014, 32279.51580726291, 32327.51508727371, 32344.606833577516, 1040.573364920379, 1046.3930920066343, 1050.5587653185528, 1114.420501339299, 1164.8019836627793, 17159.914200429168, 17791.644167116585, 17813.821861781475, 18530.44408667742, 18688.719669204973, 503247.0, 528628.0, 534641.0, 539109.0, 835873.0, 57887.421125788205, 58229.0, 58618.41381586215, 58690.41309586935, 58699.42355476223, 1209.3046498263534, 1244.3591550351757, 1279.9104062715521, 1309.4107651556646, 1312.534050412109, 29571.26071848135, 33658.66341336555, 33823.83088084544, 33955.660443396235, 34472.65527344795, 53550.46449535611, 54368.728156358964, 54459.0, 54551.0, 54574.63563410788, 466155.0, 466265.0, 467913.0, 468605.0, 469744.0, 25576.360590985376, 26199.345016374744, 26432.207033789018, 27483.725162748917, 27754.5836812452, 45400.0, 45426.77286613678, 45534.316985244586, 46785.29822052604, 46939.530604693515, 38038.2392352157, 38618.42072368861, 39087.41368879412, 40474.0, 40501.709692254495, 37516.81241593775, 38149.80925095357, 38425.80787096159, 40436.1912761749, 40455.79772101121, 39097.804510977265, 39302.0, 39457.605423945395, 39701.80149099236, 39739.40390894081, 31779.0, 35613.0, 36162.0, 36563.817180913924, 37709.622903770614, 28028.29929251785, 28216.71783282141, 28221.576676350283, 28235.858820705766, 28465.28836779097], "value": 19481.933831647544, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.7125798763948, 403.1392975996248, 403.39607831448023, 415.81080608323214, 427.3802985670776, 13259.27074010929, 15187.088774673528, 19077.713834292495, 19102.0, 19634.90182549085, 18390.632187356277, 18728.719069213974, 18904.243830246774, 18929.05354732265, 19221.519462013457, 1619.6922584708868, 1619.7975253093364, 1619.902805831654, 1620.4490473239096, 1836.4857839804843, 4267.295896177127, 4463.174312752135, 4498.550144985508, 5769.769209231626, 5850.648961062328, 6595.175603049619, 6617.8014659560295, 6724.293949135344, 6727.024581435689, 6732.697028633724, 6473.385028422316, 6608.570442921195, 6641.867162656768, 6647.833804154887, 6714.529982901199, 5656.547476201894, 5820.534357251409, 5873.442023007808, 5877.441643043904, 5879.882402351972, 21238.787612123837, 21981.450463738536, 23179.188728394427, 23551.29346119602, 23607.29178124659, 2086.0821238654944, 2268.3875353654585, 2268.943276418103, 2390.756806460648, 2869.6556413230337, 9975.451350175737, 10411.479426028744, 11074.557017719282, 11085.612003579916, 12140.3322817245, 1909.675355189621, 1913.5981443896799, 1957.9706304405445, 1982.3854605072474, 1993.5614164883702, 76594.0, 77142.0, 78925.0, 79477.99999999942, 81321.59339203325, 10628.574857005633, 10732.570697172026, 10744.409057501913, 11265.324080555192, 11411.942940285246, 15843.049417035014, 15893.443729469413, 15925.761113583305, 15935.442259520862, 15940.362385504683, 7893.407994400443, 8012.238837310446, 8137.633806478722, 8335.458195217412, 8355.0, 7540.132884718328, 7630.542167469969, 7644.655990480442, 7739.380849532024, 7822.726204582926, 4762.14281429346, 4875.731834749052, 4933.975330123399, 5006.023825354094, 5011.198208286657, 1827.853771698261, 2016.626924019061, 2032.309014934929, 2051.6819892916624, 2054.3528788431895, 912.771807048238, 917.857732051533, 918.554501066982, 924.7456949338986, 982.390917631059, 17670.469885903185, 19081.618367632847, 19457.708134377997, 19582.804171958098, 19804.20783168686, 933.3699752666922, 958.1233171647864, 962.9470379129075, 1011.544804837826, 1053.752368193474, 10109.39343639384, 10609.204309676805, 10861.348319100722, 10919.454027298603, 10986.780264394667, 428908.0, 451342.0, 453978.0, 457320.0, 461059.5357719559, 94146.0, 95886.52056739671, 99158.5042074785, 102603.0, 103309.0, 1019.1795604538418, 1044.38903241603, 1076.3972175581544, 1080.330195278932, 1080.4327727942737, 17396.39112631052, 17611.38360157388, 17905.731414028538, 18132.456026319232, 18524.907375463037, 41643.0, 42157.57842421537, 42757.57242427536, 43026.0, 43107.353389698554, 59497.702511487165, 60255.69872150611, 60734.0, 61249.0, 62125.68937155285, 8291.751247462586, 8342.374321926001, 8435.0, 8590.484570925764, 8795.296376289754, 12193.451294691582, 12706.745865082832, 12745.553905613258, 12827.551035713705, 12978.351082446028, 11105.833412498981, 11107.888921111009, 11216.943915280372, 11409.771804564029, 11592.942035289769, 10860.67417977462, 11007.284526505751, 11095.944520277348, 11196.776064478829, 11277.887221127683, 11138.721531961442, 11394.202405831513, 11397.658070257905, 11466.426678665866, 11914.0, 8263.917360826315, 8284.502929823988, 8352.791180220544, 8501.574921254036, 8596.011458682082, 8953.328500362368, 11066.944665276622, 11167.609133680282, 11305.491252893804, 11643.359615221245], "value": 8911.224692839372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.316283950925, 1451.542764029331, 1503.751880939645, 1617.2156504095512, 1774.520879362572, 8131.67473301068, 8925.464472131676, 9145.725628231146, 9236.722898313044, 9477.383970041943, 9292.210162136209, 9327.720168394959, 9339.252859771219, 9347.158755711984, 9350.719478415642, 2407.6388541718707, 2429.4898071405028, 2433.3065076453213, 2434.294054724128, 2438.1222759806456, 2339.4853132310864, 2350.5298940212024, 2394.964075538868, 2397.160993652225, 2397.304781613339, 2350.106959355451, 2367.562001029806, 2369.341460977314, 2406.4465173010217, 2422.539717453687, 2285.5771682238674, 2303.665968434576, 2312.2022902098665, 2312.329424466911, 2318.374039009458, 2244.8989795459242, 2257.3002369265582, 2265.501589650274, 2269.273832373641, 2275.5790178816974, 8850.0, 9270.119338662817, 9312.34813563051, 9317.72046838596, 9351.672691455766, 5345.412004679482, 5566.944330556683, 5573.08044172711, 5579.832605021856, 5588.21764952907, 3403.5915690117095, 3414.146463384154, 3476.3221171871496, 3501.212227248874, 3505.2989402119424, 1116.1963386361888, 1132.9546818127264, 1156.4254135413096, 1174.1428757007347, 1174.4186627619383, 13297.268650224234, 13408.731825363535, 13412.396442160127, 13417.932910335483, 13426.194428334331, 4474.418325617714, 4552.863414097583, 4631.861044168681, 4666.370040044619, 4738.763061846894, 10331.638392656221, 10349.223808214414, 10358.274920755475, 10377.221708371902, 10386.740331491623, 5426.620136590401, 5438.510534051875, 5443.618946673695, 5443.891122177534, 5445.809396671177, 4348.239058164809, 4500.167469018242, 4926.162552366106, 5041.747912604355, 5079.6952182869145, 3903.6096390360735, 3977.2840888639744, 4046.59534046593, 4088.5298190707854, 4127.772972486482, 1132.7111586545543, 1149.316156886651, 1152.8040233160382, 1167.171308371049, 1190.9047276217882, 717.0248462091552, 873.4977388001925, 891.0688330694439, 926.2451102351562, 947.190152419685, 6150.50795936324, 6181.289151747516, 6201.348858369896, 6278.937210627927, 6366.6180029198395, 460.6913368043419, 468.6742713813867, 476.89508308172145, 479.33851285226166, 487.200990375784, 4169.854055108117, 4253.48958125027, 4264.914701705949, 4333.7833108345085, 4352.194843953878, 11403.372814495116, 11799.52801887932, 11839.408029598486, 11869.169158158846, 11897.40512974365, 11718.121140914294, 11764.235324704037, 11780.70548236301, 11817.822732659017, 11829.763404731857, 8188.426810123353, 8572.571371431528, 8583.742487725378, 8671.956640216758, 8745.650173993095, 6238.126662267196, 6299.653519056313, 6405.295417503978, 6471.7734879279, 6474.158359413246, 10858.45707714627, 10952.890471095187, 10962.506687198937, 11243.0, 11256.606018789304, 10917.727056823644, 11087.390193539433, 11152.386618736047, 11191.944040279746, 11264.09887209005, 8171.509709417454, 8200.630971606412, 8284.668613255402, 8342.207490288412, 8421.115782842959, 8498.872516912128, 8536.658533658832, 8537.786555336166, 8556.0, 8563.015253245956, 8175.0, 8230.21812927771, 8242.258196762321, 8249.670013199644, 8395.160483951557, 7965.80085497844, 8009.279164875189, 8078.434509584273, 8098.3116435103675, 8117.1071182168735, 7369.631518424165, 7417.221191774786, 7455.291747283999, 7497.325240728361, 7522.623868806647, 5315.335583052119, 5364.946350536445, 5401.738731414447, 5447.891042179055, 5604.075327570905, 5290.841274761763, 5378.677279363251, 5404.513593776579, 5445.6732596042775, 5491.423400543045], "value": 4697.0669165223435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.07294757049186, 334.89450822990756, 334.9480830471278, 336.1428357687896, 346.1950964008679, 5266.683998960065, 5282.784959459322, 5317.9202311965355, 5325.360956685194, 5325.7869685212645, 5206.479352064792, 5219.747260657444, 5277.6305658603915, 5292.259083728281, 5296.126139187029, 3888.7472314299553, 4006.519217693881, 4073.40935564343, 4174.791260436981, 4180.770057646828, 4127.153933443652, 4132.814023368941, 4199.811008504625, 4218.535961044283, 4219.810108545107, 4103.076807718269, 4108.5686002969705, 4119.752814831119, 4122.021429713739, 4176.749395036308, 4024.1750441159493, 4101.589841015874, 4103.302438585448, 4111.198316328318, 4130.731502452346, 3626.6192049834654, 3636.890893273206, 3647.288778688157, 3652.726045546595, 3654.3422184006868, 5074.31496747938, 5098.694078355311, 5105.897882042375, 5130.820421285274, 5160.225966105077, 4502.842400515966, 4671.813127474897, 4715.009847931939, 4719.882002949919, 4731.290306454025, 5449.509544141007, 5449.782008719647, 5462.8361149165585, 5496.890062198773, 5520.033994051025, 4137.91724165518, 4144.378343248507, 4146.3158578834455, 4147.834086636531, 4148.688848336386, 5540.703281547473, 5615.494605485526, 5653.547716182696, 5801.767929282823, 5828.766849326022, 4156.168766246763, 4191.350340697197, 4198.6641068714425, 4199.853005144805, 4224.957750422518, 4257.574242575718, 4263.722858014219, 4268.722533035343, 4277.0, 4278.251306021496, 4417.712848664891, 4789.928151077736, 4792.95207047932, 4808.687435316763, 4809.591184749335, 5401.405845357008, 5419.485148910847, 5421.918671219935, 5432.69615292327, 5461.617686761968, 1427.571728481472, 1444.2562080528537, 1445.7831325301079, 1450.3038541500139, 1450.6953539756557, 935.9423851048272, 954.3987288008649, 964.6382606522554, 977.8217248215883, 987.8195556310284, 5052.686301561621, 5181.663191892515, 5324.201369794562, 5338.332708411449, 5342.54588359986, 5603.355614104348, 5618.381977982419, 5639.3232812062815, 5642.407547207484, 5663.575231857627, 5116.386033675983, 5320.281761962163, 5340.546053585411, 5347.839564813062, 5387.488188622075, 2910.2869796899895, 2984.7164519370626, 2986.970130298713, 2998.835064071497, 3002.6997300270236, 5837.854053648693, 5848.0, 5868.501177399969, 5869.679322152567, 5872.383399742966, 5555.63825828061, 5749.1663708762, 5764.933487304778, 5765.567582431335, 5826.669332267043, 5789.784145329444, 5822.359540450547, 5843.824685259364, 5870.006296788754, 5891.617044892068, 5439.211314359475, 5640.884668249407, 5661.2923384576925, 5700.429957004267, 5701.40135285789, 5590.9440905592055, 5606.6636001840025, 5611.971940140274, 5622.24099746529, 5647.519960803378, 5797.4782269595935, 5829.53363730892, 5863.530917526673, 5874.383189765013, 5876.941230587639, 3656.62876421342, 3658.725595580289, 3683.4290684943876, 3757.8121093945742, 3772.302124107049, 4995.977433652733, 5013.799448022039, 5021.8362431669275, 5088.676943994514, 5119.948800511947, 4942.4316203637045, 4991.675541089818, 4991.750412479434, 5019.017488092256, 5054.570361519165, 4768.57082862544, 4888.022395520981, 4937.494064310276, 4942.851714448572, 4974.2890124881305, 4836.854894353175, 4838.637102217277, 4853.781579828987, 4863.0, 4878.390201224847, 5335.0, 5399.973000134974, 5454.836354909359, 5499.587530935116, 5526.972365138309, 5395.843431592562, 5402.216678581505, 5403.108487099742, 5435.429279925709, 5438.972805135949], "value": 4172.435837154772, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.787259567321, 776.6272189349112, 787.5589669784921, 793.3098204562032, 804.19178725381, 13866.098703584272, 14120.858791412084, 14179.432822687106, 14234.43062277509, 14258.429662813487, 13835.654108647288, 14309.78535321971, 14350.138991660508, 15017.0, 15133.09201447911, 791.244361634639, 809.2393150438598, 813.4590497319275, 813.7680760983121, 813.9186081391844, 651.8207492939432, 673.85848971716, 805.8509175802466, 807.9192080791903, 808.4340961327065, 817.6606708216113, 844.7085755414372, 855.3200205836375, 856.6059612578222, 857.1557016338901, 842.9957850210741, 843.068409407608, 843.6541018182515, 844.1431946574232, 844.1980118887082, 380.3628921556382, 388.35533015194926, 619.8357435279654, 620.3951147631069, 620.8168590265882, 14143.646408839759, 14682.779758303634, 14772.187529685863, 15112.848871511364, 15245.085294882343, 14884.255787210595, 15252.23738813066, 15299.617509562238, 15804.367825286974, 15831.208439577975, 11795.292282462993, 11846.407679615984, 12072.456739446747, 12234.632961011095, 13072.281024543647, 3899.980500097481, 3954.189391174802, 4084.1219137885496, 4117.91764164718, 4118.608732170439, 77867.22132778657, 78928.21071789267, 79309.0, 79974.0, 80204.59897700533, 10831.566737330577, 11487.138464615187, 11550.711232219262, 11862.644120676394, 12073.57742479009, 21613.891930540245, 21897.781022189574, 21911.342659720234, 21946.89026554857, 21950.231741889278, 7881.5665138417935, 8506.532140732319, 8569.442986206, 8672.046074931752, 8682.565871706389, 4320.697551171388, 4487.798049087799, 4501.122281155143, 4525.502194758574, 4700.788464519036, 3625.9818700906853, 3770.8868733938025, 3802.752821066622, 3803.448499967489, 3812.256609961031, 592.9021711417654, 597.5816928150313, 599.3407252022827, 607.902735562308, 629.3046183966742, 674.3728332650584, 692.7852365766629, 699.2832346844424, 709.6168069242551, 718.1023720349563, 9289.953550232342, 9638.303357195247, 10123.797524049478, 10382.480875956171, 10445.425501597334, 547.4689551135392, 602.5661523702927, 644.5488158289216, 717.3866344275674, 719.8884172953201, 4447.644188464915, 4498.775061246924, 4847.224444088929, 4894.452821204658, 4952.826651067267, 115307.84692153138, 124106.0, 124431.37784311021, 126025.0, 129535.0, 64275.678621606596, 82501.58749206216, 98341.5082924595, 106304.0, 112908.0, 79228.20771792323, 82655.586722066, 88882.55558722165, 89792.5510372444, 92991.0, 9452.95273523628, 9559.474228917197, 9568.712938611852, 9989.700308990741, 10211.438370889375, 52136.77757276365, 52986.0, 53252.201216981004, 54132.18801718056, 54955.0, 61838.69080654568, 63114.68442657757, 63399.36600634119, 76867.61566192357, 80927.19072809197, 13299.734005319646, 13504.86495135036, 13550.79673804874, 13634.454621815414, 13641.24973126448, 14318.14091154534, 14758.704825903207, 15147.54557363281, 15583.0, 15800.76298855495, 13514.459421623025, 13769.518066868011, 14087.0, 14183.929080354945, 14217.146971181763, 14039.298035098, 14492.782608260673, 14704.347696451683, 14715.190664513126, 15344.693106138038, 12528.498860045496, 13149.934250328688, 13477.393517291941, 13617.591472255848, 14041.789373159616, 11103.278286911325, 11182.66452006441, 11223.326600404002, 11603.6518904433, 11624.18630695877, 11348.319100853974, 11519.202831625327, 11662.241954272946, 11759.176857620227, 11868.287902725864], "value": 7553.240286610171, "unit": "score"}], "name": "dromaeo_css", "value": 7150.558466491337, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 110.0, 112.0, 109.0, 111.0, 110.0, 110.0, 110.0, 109.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 122.0, 161.0, 120.0, 127.0, 124.0, 124.0, 153.0, 126.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 162.0, 161.0, 164.0, 162.0, 163.0, 163.0, 163.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 114.0, 76.0, 75.0, 76.0, 75.0, 99.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 123.0, 82.0, 83.0, 82.0, 102.0, 82.0, 83.0, 111.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 144.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 50.0, 50.0, 51.0, 51.0, 50.0, 51.0, 51.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 79.0, 81.0, 84.0, 78.0, 84.0, 79.0, 79.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 123.0, 125.0, 133.0, 125.0, 126.0, 134.0, 169.0, 139.0], "value": 132.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 165.0, 171.0, 164.0, 170.0, 167.0, 169.0, 166.0, 168.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1492.1, "alertThreshold": 2.0}]} 08:31:30 INFO - Return code: 0 08:31:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:31:30 INFO - # TBPL SUCCESS # 08:31:30 INFO - Running post-action listener: _resource_record_post_action 08:31:30 INFO - [mozharness: 2016-09-17 15:31:30.498061Z] Finished run-tests step (success) 08:31:30 INFO - Running post-run listener: _resource_record_post_run 08:31:30 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402870272; Read time: 16; Write time: 353292 08:31:30 INFO - TinderboxPrint: CPU usage
13.1% 08:31:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:31:30 INFO - TinderboxPrint: I/O write bytes / time
402,870,272 / 353,292 08:31:30 INFO - TinderboxPrint: CPU idle
8,286.4 (86.9%) 08:31:30 INFO - TinderboxPrint: CPU user
1,196.4 (12.5%) 08:31:30 INFO - TinderboxPrint: Swap in / out
0 / 0 08:31:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105578496; Read time: 0; Write time: 109328 08:31:30 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 296341504; Read time: 0; Write time: 243952 08:31:31 INFO - Running post-run listener: _upload_blobber_files 08:31:31 INFO - Blob upload gear active. 08:31:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:31 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:31:31 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:31:31 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:31:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:32 INFO - (blobuploader) - INFO - Done attempting. 08:31:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:33 INFO - (blobuploader) - INFO - Done attempting. 08:31:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:37 INFO - (blobuploader) - INFO - Done attempting. 08:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:37 INFO - Return code: 0 08:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/722dd706c76977e0990294371aa13ebd415fad81ed8346627aa62d822511dab104088db63b22cdf256ddde7bd96bf5431d59b078469017e763c9baa8f4fae9a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c21de8cd96c1ec12d640a17290cc696375cce27f4b8f600aefdc0eee55336b52444ec65681a5f99cff6d63d1208429b34278f0aae05894e85b325d916144064b"} 08:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:37 INFO - Contents: 08:31:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/722dd706c76977e0990294371aa13ebd415fad81ed8346627aa62d822511dab104088db63b22cdf256ddde7bd96bf5431d59b078469017e763c9baa8f4fae9a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c21de8cd96c1ec12d640a17290cc696375cce27f4b8f600aefdc0eee55336b52444ec65681a5f99cff6d63d1208429b34278f0aae05894e85b325d916144064b"} 08:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:37 INFO - Copying logs to upload dir... 08:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.141308 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-17 08:31:36.874132) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:36.878406) ========= 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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 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/722dd706c76977e0990294371aa13ebd415fad81ed8346627aa62d822511dab104088db63b22cdf256ddde7bd96bf5431d59b078469017e763c9baa8f4fae9a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c21de8cd96c1ec12d640a17290cc696375cce27f4b8f600aefdc0eee55336b52444ec65681a5f99cff6d63d1208429b34278f0aae05894e85b325d916144064b"} 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.011381 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/722dd706c76977e0990294371aa13ebd415fad81ed8346627aa62d822511dab104088db63b22cdf256ddde7bd96bf5431d59b078469017e763c9baa8f4fae9a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c21de8cd96c1ec12d640a17290cc696375cce27f4b8f600aefdc0eee55336b52444ec65681a5f99cff6d63d1208429b34278f0aae05894e85b325d916144064b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:36.907769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:36.908095) ========= 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=0333a256a5e4f052382dd180000001b1-1474125058.441979-522386876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:36.993705) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:31:36.994018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:31:36.994334) ========= ========= Total master_lag: 0.19 =========