builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1474078437.8 results: success (0) buildid: 20160916163001 builduid: 381c1d02a4704399a3d95ee7e33a243b revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:57.796787) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:57.797235) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:57.797511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:57.842555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:57.842824) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 19:14:00-- 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% 17.5M=0.001s 2016-09-16 19:14:00 (17.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161975 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:58.020052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:58.020373) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023285 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:58.074277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-16 19:13:58.074640) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 19:14:00,837 truncating revision to first 12 chars 2016-09-16 19:14:00,837 Setting DEBUG logging. 2016-09-16 19:14:00,837 attempt 1/10 2016-09-16 19:14:00,837 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-16 19:14:02,035 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.516919 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-16 19:13:59.605114) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:59.605415) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:59.605830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:59.606108) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 19:13:59.616846) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-16 19:13:59.617142) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:14:02 INFO - MultiFileLogger online at 20160916 19:14:02 in /builds/slave/test 19:14: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 19:14:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:02 INFO - {'append_to_log': False, 19:14:02 INFO - 'base_work_dir': '/builds/slave/test', 19:14:02 INFO - 'blob_upload_branch': 'Firefox', 19:14:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:02 INFO - 'branch': 'Firefox', 19:14:02 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:02 INFO - 'config_files': ('talos/linux_config.py',), 19:14:02 INFO - 'default_actions': ('clobber', 19:14:02 INFO - 'read-buildbot-config', 19:14:02 INFO - 'download-and-extract', 19:14:02 INFO - 'populate-webroot', 19:14:02 INFO - 'create-virtualenv', 19:14:02 INFO - 'install', 19:14:02 INFO - 'run-tests'), 19:14:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:02 INFO - 'download_minidump_stackwalk': True, 19:14:02 INFO - 'download_symbols': 'ondemand', 19:14:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:02 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:02 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:02 INFO - 'installer_path': 'installer.exe', 19:14:02 INFO - 'log_level': 'info', 19:14:02 INFO - 'log_name': 'talos', 19:14:02 INFO - 'log_to_console': True, 19:14:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:14:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:14:02 INFO - 'opt_config_files': (), 19:14:02 INFO - 'pip_index': False, 19:14:02 INFO - 'sps_profile': False, 19:14:02 INFO - 'sps_profile_interval': 0, 19:14:02 INFO - 'suite': 'dromaeojs', 19:14:02 INFO - 'system_bits': '32', 19:14:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:02 INFO - 'title': 'talos-linux64-ix-064', 19:14:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:02 INFO - 'use_talos_json': True, 19:14:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:02 INFO - 'work_dir': 'build'} 19:14:02 INFO - [mozharness: 2016-09-17 02:14:02.472746Z] Running clobber step. 19:14:02 INFO - Running pre-action listener: _resource_record_pre_action 19:14:02 INFO - Running main action method: clobber 19:14:02 INFO - rmtree: /builds/slave/test/build 19:14:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:14:02 INFO - Running post-action listener: _resource_record_post_action 19:14:02 INFO - [mozharness: 2016-09-17 02:14:02.940478Z] Finished clobber step (success) 19:14:02 INFO - [mozharness: 2016-09-17 02:14:02.940582Z] Running read-buildbot-config step. 19:14:02 INFO - Running pre-action listener: _resource_record_pre_action 19:14:02 INFO - Running main action method: read_buildbot_config 19:14:02 INFO - Using buildbot properties: 19:14:02 INFO - { 19:14:02 INFO - "project": "", 19:14:02 INFO - "product": "firefox", 19:14:02 INFO - "script_repo_revision": "production", 19:14:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:14:02 INFO - "repository": "", 19:14:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:14:02 INFO - "buildid": "20160916163001", 19:14:02 INFO - "pgo_build": "True", 19:14:02 INFO - "basedir": "/builds/slave/test", 19:14:02 INFO - "buildnumber": 572, 19:14:02 INFO - "slavename": "talos-linux64-ix-064", 19:14:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:14:02 INFO - "platform": "ubuntu64_hw", 19:14:02 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:14:02 INFO - "branch": "mozilla-central", 19:14:02 INFO - "repo_path": "mozilla-central", 19:14:02 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 19:14:02 INFO - "stage_platform": "linux64-pgo", 19:14:02 INFO - "builduid": "381c1d02a4704399a3d95ee7e33a243b", 19:14:02 INFO - "slavebuilddir": "test" 19:14:02 INFO - } 19:14:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:14:02 INFO - Running post-action listener: _resource_record_post_action 19:14:02 INFO - [mozharness: 2016-09-17 02:14:02.943060Z] Finished read-buildbot-config step (success) 19:14:02 INFO - [mozharness: 2016-09-17 02:14:02.943151Z] Running download-and-extract step. 19:14:02 INFO - Running pre-action listener: _resource_record_pre_action 19:14:02 INFO - Running main action method: download_and_extract 19:14:02 INFO - mkdir: /builds/slave/test/build/tests 19:14: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')]} 19:14:02 INFO - trying https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:14:02 INFO - Downloading https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:14:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:14:03 INFO - Downloaded 1361 bytes. 19:14:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:14:03 INFO - Using the following test package requirements: 19:14:03 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:14:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:14:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:14:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'jsshell-linux-x86_64.zip'], 19:14:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:14:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:14:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:14:03 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:14:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:14:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:14:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:14:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:14:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:14:03 INFO - Fetch https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:14:04 INFO - Expected file size: 21936347 19:14:08 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 19:14:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:14:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:14:08 INFO - Fetch https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:14:08 INFO - Expected file size: 21936347 19:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:14:14 INFO - Expected file size: 14414048 19:14:14 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')]} 19:14:14 INFO - trying https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:14:14 INFO - Downloading https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:14:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:14:15 INFO - Downloaded 59535581 bytes. 19:14:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:14:15 INFO - mkdir: /builds/slave/test/properties 19:14:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:14:15 INFO - Writing to file /builds/slave/test/properties/build_url 19:14:15 INFO - Contents: 19:14:15 INFO - build_url:https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:14:16 INFO - Running post-action listener: _resource_record_post_action 19:14:16 INFO - Running post-action listener: set_extra_try_arguments 19:14:16 INFO - [mozharness: 2016-09-17 02:14:16.494400Z] Finished download-and-extract step (success) 19:14:16 INFO - [mozharness: 2016-09-17 02:14:16.494630Z] Running populate-webroot step. 19:14:16 INFO - Running pre-action listener: _resource_record_pre_action 19:14:16 INFO - Running main action method: populate_webroot 19:14:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:14:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:14:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:14:16 INFO - '--tppagecycles', 19:14:16 INFO - '7'], 19:14:16 INFO - 'tests': ['tsvgm']}}, 19:14:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:14:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:14:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:14:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:14:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['damp', 'tps']}, 19:14:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'tests': ['damp', 'tps']}, 19:14:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['dromaeo_dom']}, 19:14:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:14:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['basic_compositor_video']}, 19:14:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:14:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'other-e10s': {'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['a11yr', 19:14:16 INFO - 'ts_paint', 19:14:16 INFO - 'tpaint', 19:14:16 INFO - 'sessionrestore', 19:14:16 INFO - 'sessionrestore_no_auto_restore', 19:14:16 INFO - 'tabpaint']}, 19:14:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['tsvgx', 19:14:16 INFO - 'tsvgr_opacity', 19:14:16 INFO - 'tart', 19:14:16 INFO - 'tscrollx', 19:14:16 INFO - 'cart']}, 19:14:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:14:16 INFO - 'tsvgr_opacity', 19:14:16 INFO - 'tart', 19:14:16 INFO - 'tscrollx', 19:14:16 INFO - 'cart']}, 19:14:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'talos_options': ['--disable-e10s'], 19:14:16 INFO - 'tests': ['tp5o']}, 19:14:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'tests': ['tp5o']}, 19:14:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'talos_options': ['--disable-e10s', 19:14:16 INFO - '--xperf_path', 19:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:16 INFO - 'tests': ['tp5n']}, 19:14:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:16 INFO - 'talos_options': ['--xperf_path', 19:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:16 INFO - 'tests': ['tp5n']}}, 19:14:16 INFO - 'talos.zip': {'path': '', 19:14:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:14:16 INFO - Running post-action listener: _resource_record_post_action 19:14:16 INFO - [mozharness: 2016-09-17 02:14:16.510950Z] Finished populate-webroot step (success) 19:14:16 INFO - [mozharness: 2016-09-17 02:14:16.511042Z] Running create-virtualenv step. 19:14:16 INFO - Running pre-action listener: _resource_record_pre_action 19:14:16 INFO - Running main action method: create_virtualenv 19:14:16 INFO - Creating virtualenv /builds/slave/test/build/venv 19:14:16 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 19:14:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:14:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:14:16 INFO - Using real prefix '/usr' 19:14:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:14:17 INFO - Installing distribute.............................................................................................................................................................................................done. 19:14:19 INFO - Installing pip.................done. 19:14:19 INFO - Return code: 0 19:14:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:14:19 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')]} 19:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:19 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')]} 19:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:19 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:19 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 19:14:19 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 19:14:19 INFO - Using env: {'DISPLAY': ':0', 19:14:19 INFO - 'HOME': '/home/cltbld', 19:14:19 INFO - 'LANG': 'en_US.UTF-8', 19:14:19 INFO - 'LANGUAGE': 'en_US:en', 19:14:19 INFO - 'LOGNAME': 'cltbld', 19:14:19 INFO - 'MAIL': '/var/mail/cltbld', 19:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:19 INFO - 'MOZ_NO_REMOTE': '1', 19:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:19 INFO - 'NO_EM_RESTART': '1', 19:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:19 INFO - 'PWD': '/builds/slave/test', 19:14:19 INFO - 'SHELL': '/bin/bash', 19:14:19 INFO - 'SHLVL': '1', 19:14:19 INFO - 'TERM': 'linux', 19:14:19 INFO - 'TMOUT': '86400', 19:14:19 INFO - 'USER': 'cltbld', 19:14:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:19 INFO - '_': '/tools/buildbot/bin/python'} 19:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:19 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 19:14:19 INFO - Cleaning up... 19:14:19 INFO - Return code: 0 19:14:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:14:19 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')]} 19:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:19 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')]} 19:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:19 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:19 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 19:14:19 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 19:14:19 INFO - Using env: {'DISPLAY': ':0', 19:14:19 INFO - 'HOME': '/home/cltbld', 19:14:19 INFO - 'LANG': 'en_US.UTF-8', 19:14:19 INFO - 'LANGUAGE': 'en_US:en', 19:14:19 INFO - 'LOGNAME': 'cltbld', 19:14:19 INFO - 'MAIL': '/var/mail/cltbld', 19:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:19 INFO - 'MOZ_NO_REMOTE': '1', 19:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:19 INFO - 'NO_EM_RESTART': '1', 19:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:19 INFO - 'PWD': '/builds/slave/test', 19:14:19 INFO - 'SHELL': '/bin/bash', 19:14:19 INFO - 'SHLVL': '1', 19:14:19 INFO - 'TERM': 'linux', 19:14:19 INFO - 'TMOUT': '86400', 19:14:19 INFO - 'USER': 'cltbld', 19:14:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:19 INFO - '_': '/tools/buildbot/bin/python'} 19:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:19 INFO - Downloading/unpacking psutil>=3.1.1 19:14:19 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 19:14:19 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 19:14:19 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 19:14:19 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 19:14:19 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 19:14:19 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 19:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:21 INFO - Installing collected packages: psutil 19:14:21 INFO - Running setup.py install for psutil 19:14:21 INFO - building 'psutil._psutil_linux' extension 19:14:21 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 19:14:21 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 19:14:21 INFO - building 'psutil._psutil_posix' extension 19:14:21 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 19:14:21 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 19:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:22 INFO - Successfully installed psutil 19:14:22 INFO - Cleaning up... 19:14:22 INFO - Return code: 0 19:14:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:14:22 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')]} 19:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:22 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')]} 19:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:22 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:22 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 19:14:22 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 19:14:22 INFO - Using env: {'DISPLAY': ':0', 19:14:22 INFO - 'HOME': '/home/cltbld', 19:14:22 INFO - 'LANG': 'en_US.UTF-8', 19:14:22 INFO - 'LANGUAGE': 'en_US:en', 19:14:22 INFO - 'LOGNAME': 'cltbld', 19:14:22 INFO - 'MAIL': '/var/mail/cltbld', 19:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:22 INFO - 'MOZ_NO_REMOTE': '1', 19:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:22 INFO - 'NO_EM_RESTART': '1', 19:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:22 INFO - 'PWD': '/builds/slave/test', 19:14:22 INFO - 'SHELL': '/bin/bash', 19:14:22 INFO - 'SHLVL': '1', 19:14:22 INFO - 'TERM': 'linux', 19:14:22 INFO - 'TMOUT': '86400', 19:14:22 INFO - 'USER': 'cltbld', 19:14:22 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:22 INFO - '_': '/tools/buildbot/bin/python'} 19:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:14:24 INFO - Installing collected packages: mozsystemmonitor 19:14:24 INFO - Running setup.py install for mozsystemmonitor 19:14:24 INFO - Successfully installed mozsystemmonitor 19:14:24 INFO - Cleaning up... 19:14:24 INFO - Return code: 0 19:14:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:14:24 INFO - Using env: {'DISPLAY': ':0', 19:14:24 INFO - 'HOME': '/home/cltbld', 19:14:24 INFO - 'LANG': 'en_US.UTF-8', 19:14:24 INFO - 'LANGUAGE': 'en_US:en', 19:14:24 INFO - 'LOGNAME': 'cltbld', 19:14:24 INFO - 'MAIL': '/var/mail/cltbld', 19:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:24 INFO - 'MOZ_NO_REMOTE': '1', 19:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:24 INFO - 'NO_EM_RESTART': '1', 19:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:24 INFO - 'PWD': '/builds/slave/test', 19:14:24 INFO - 'SHELL': '/bin/bash', 19:14:24 INFO - 'SHLVL': '1', 19:14:24 INFO - 'TERM': 'linux', 19:14:24 INFO - 'TMOUT': '86400', 19:14:24 INFO - 'USER': 'cltbld', 19:14:24 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:24 INFO - '_': '/tools/buildbot/bin/python'} 19:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:24 INFO - Downloading/unpacking jsonschema==2.5.1 19:14:24 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 19:14:24 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 19:14:24 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 19:14:24 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 19:14:24 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 19:14:24 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 19:14:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:14:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:26 INFO - Downloading functools32-3.2.3-2.tar.gz 19:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:14:26 INFO - warning: no files found matching '*.txt' 19:14:26 INFO - no previously-included directories found matching 'build' 19:14:26 INFO - no previously-included directories found matching 'dist' 19:14:26 INFO - no previously-included directories found matching '.git*' 19:14:26 INFO - Installing collected packages: jsonschema, functools32 19:14:26 INFO - Running setup.py install for functools32 19:14:26 INFO - warning: no files found matching '*.txt' 19:14:26 INFO - no previously-included directories found matching 'build' 19:14:26 INFO - no previously-included directories found matching 'dist' 19:14:26 INFO - no previously-included directories found matching '.git*' 19:14:26 INFO - Successfully installed jsonschema functools32 19:14:26 INFO - Cleaning up... 19:14:26 INFO - Return code: 0 19:14:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:26 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:26 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 19:14:26 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 19:14:26 INFO - Using env: {'DISPLAY': ':0', 19:14:26 INFO - 'HOME': '/home/cltbld', 19:14:26 INFO - 'LANG': 'en_US.UTF-8', 19:14:26 INFO - 'LANGUAGE': 'en_US:en', 19:14:26 INFO - 'LOGNAME': 'cltbld', 19:14:26 INFO - 'MAIL': '/var/mail/cltbld', 19:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:26 INFO - 'MOZ_NO_REMOTE': '1', 19:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:26 INFO - 'NO_EM_RESTART': '1', 19:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:26 INFO - 'PWD': '/builds/slave/test', 19:14:26 INFO - 'SHELL': '/bin/bash', 19:14:26 INFO - 'SHLVL': '1', 19:14:26 INFO - 'TERM': 'linux', 19:14:26 INFO - 'TMOUT': '86400', 19:14:26 INFO - 'USER': 'cltbld', 19:14:26 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:26 INFO - '_': '/tools/buildbot/bin/python'} 19:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:14:27 INFO - Cleaning up... 19:14:27 INFO - Return code: 0 19:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:14:27 INFO - Using env: {'DISPLAY': ':0', 19:14:27 INFO - 'HOME': '/home/cltbld', 19:14:27 INFO - 'LANG': 'en_US.UTF-8', 19:14:27 INFO - 'LANGUAGE': 'en_US:en', 19:14:27 INFO - 'LOGNAME': 'cltbld', 19:14:27 INFO - 'MAIL': '/var/mail/cltbld', 19:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:27 INFO - 'MOZ_NO_REMOTE': '1', 19:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:27 INFO - 'NO_EM_RESTART': '1', 19:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:27 INFO - 'PWD': '/builds/slave/test', 19:14:27 INFO - 'SHELL': '/bin/bash', 19:14:27 INFO - 'SHLVL': '1', 19:14:27 INFO - 'TERM': 'linux', 19:14:27 INFO - 'TMOUT': '86400', 19:14:27 INFO - 'USER': 'cltbld', 19:14:27 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:27 INFO - '_': '/tools/buildbot/bin/python'} 19:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 19:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 19:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:14:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:14:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 INFO - Downloading docopt-0.6.1.tar.gz 19:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 19:14:30 INFO - Running setup.py install for blobuploader 19:14:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:14:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:14:30 INFO - Running setup.py install for requests 19:14:30 INFO - Running setup.py install for docopt 19:14:30 INFO - Successfully installed blobuploader requests docopt 19:14:30 INFO - Cleaning up... 19:14:30 INFO - Return code: 0 19:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:30 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:30 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 19:14:30 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 19:14:30 INFO - Using env: {'DISPLAY': ':0', 19:14:30 INFO - 'HOME': '/home/cltbld', 19:14:30 INFO - 'LANG': 'en_US.UTF-8', 19:14:30 INFO - 'LANGUAGE': 'en_US:en', 19:14:30 INFO - 'LOGNAME': 'cltbld', 19:14:30 INFO - 'MAIL': '/var/mail/cltbld', 19:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:30 INFO - 'MOZ_NO_REMOTE': '1', 19:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:30 INFO - 'NO_EM_RESTART': '1', 19:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:30 INFO - 'PWD': '/builds/slave/test', 19:14:30 INFO - 'SHELL': '/bin/bash', 19:14:30 INFO - 'SHLVL': '1', 19:14:30 INFO - 'TERM': 'linux', 19:14:30 INFO - 'TMOUT': '86400', 19:14:30 INFO - 'USER': 'cltbld', 19:14:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:30 INFO - '_': '/tools/buildbot/bin/python'} 19:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:30 INFO - Running setup.py (path:/tmp/pip-noCP6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:30 INFO - Running setup.py (path:/tmp/pip-yya0fP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:30 INFO - Running setup.py (path:/tmp/pip-t1uawI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:31 INFO - Running setup.py (path:/tmp/pip-P_7_Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:31 INFO - Running setup.py (path:/tmp/pip-XvjS_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:31 INFO - Running setup.py (path:/tmp/pip-CYzsKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:31 INFO - Running setup.py (path:/tmp/pip-TxV853-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:31 INFO - Running setup.py (path:/tmp/pip-IrQz1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:31 INFO - Running setup.py (path:/tmp/pip-qHor5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:31 INFO - Running setup.py (path:/tmp/pip-j_HBF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:31 INFO - Running setup.py (path:/tmp/pip-NJnrFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:31 INFO - Running setup.py (path:/tmp/pip-2iiXll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:31 INFO - Running setup.py (path:/tmp/pip-KYpaL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:31 INFO - Running setup.py (path:/tmp/pip-c95L7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:31 INFO - Running setup.py (path:/tmp/pip-MoFcRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:31 INFO - Running setup.py (path:/tmp/pip-_ipcvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:31 INFO - Running setup.py (path:/tmp/pip-7urVew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:14:32 INFO - Running setup.py install for manifestparser 19:14:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Running setup.py install for mozcrash 19:14:32 INFO - Running setup.py install for mozdebug 19:14:32 INFO - Running setup.py install for mozdevice 19:14:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Running setup.py install for mozfile 19:14:32 INFO - Running setup.py install for mozhttpd 19:14:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Running setup.py install for mozinfo 19:14:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Running setup.py install for mozInstall 19:14:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:14:32 INFO - Running setup.py install for mozleak 19:14:32 INFO - Running setup.py install for mozlog 19:14:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for moznetwork 19:14:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozprocess 19:14:33 INFO - Running setup.py install for mozprofile 19:14:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozrunner 19:14:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozscreenshot 19:14:33 INFO - Running setup.py install for moztest 19:14:33 INFO - Running setup.py install for mozversion 19:14:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:14:33 INFO - Cleaning up... 19:14:33 INFO - Return code: 0 19:14:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:33 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')]} 19:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:33 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')]} 19:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:33 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:33 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 19:14:33 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 19:14:33 INFO - Using env: {'DISPLAY': ':0', 19:14:33 INFO - 'HOME': '/home/cltbld', 19:14:33 INFO - 'LANG': 'en_US.UTF-8', 19:14:33 INFO - 'LANGUAGE': 'en_US:en', 19:14:33 INFO - 'LOGNAME': 'cltbld', 19:14:33 INFO - 'MAIL': '/var/mail/cltbld', 19:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:33 INFO - 'MOZ_NO_REMOTE': '1', 19:14:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:33 INFO - 'NO_EM_RESTART': '1', 19:14:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:33 INFO - 'PWD': '/builds/slave/test', 19:14:33 INFO - 'SHELL': '/bin/bash', 19:14:33 INFO - 'SHLVL': '1', 19:14:33 INFO - 'TERM': 'linux', 19:14:33 INFO - 'TMOUT': '86400', 19:14:33 INFO - 'USER': 'cltbld', 19:14:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:33 INFO - '_': '/tools/buildbot/bin/python'} 19:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:34 INFO - Running setup.py (path:/tmp/pip-0R2TUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:34 INFO - Running setup.py (path:/tmp/pip-c51zXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:34 INFO - Running setup.py (path:/tmp/pip-byyoLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:34 INFO - Running setup.py (path:/tmp/pip-x3Jg4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:34 INFO - Running setup.py (path:/tmp/pip-sQY9ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:34 INFO - Running setup.py (path:/tmp/pip-HAauZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:34 INFO - Running setup.py (path:/tmp/pip-lwgWo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:34 INFO - Running setup.py (path:/tmp/pip-AbWB9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:34 INFO - Running setup.py (path:/tmp/pip-Wj5ufd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:34 INFO - Running setup.py (path:/tmp/pip-7tMpNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:34 INFO - Running setup.py (path:/tmp/pip-GfVhxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:34 INFO - Running setup.py (path:/tmp/pip-JLZ7zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:34 INFO - Running setup.py (path:/tmp/pip-gseEsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:34 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)) 19:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:35 INFO - Running setup.py (path:/tmp/pip-kKIaaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:35 INFO - Running setup.py (path:/tmp/pip-EGOn4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:35 INFO - Running setup.py (path:/tmp/pip-Ck_TmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:35 INFO - Running setup.py (path:/tmp/pip-dT9mjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:35 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)) 19:14:35 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)) 19:14:35 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)) 19:14:35 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)) 19:14:35 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)) 19:14:35 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)) 19:14:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:37 INFO - Downloading blessings-1.6.tar.gz 19:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:14:37 INFO - Installing collected packages: blessings 19:14:37 INFO - Running setup.py install for blessings 19:14:37 INFO - Successfully installed blessings 19:14:37 INFO - Cleaning up... 19:14:37 INFO - Return code: 0 19:14:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:37 INFO - Reading from file tmpfile_stdout 19:14:37 INFO - Current package versions: 19:14:37 INFO - argparse == 1.2.1 19:14:37 INFO - blessings == 1.6 19:14:37 INFO - blobuploader == 1.2.4 19:14:37 INFO - docopt == 0.6.1 19:14:37 INFO - functools32 == 3.2.3-2 19:14:37 INFO - jsonschema == 2.5.1 19:14:37 INFO - manifestparser == 1.1 19:14:37 INFO - mozInstall == 1.12 19:14:37 INFO - mozcrash == 0.17 19:14:37 INFO - mozdebug == 0.1 19:14:37 INFO - mozdevice == 0.48 19:14:37 INFO - mozfile == 1.2 19:14:37 INFO - mozhttpd == 0.7 19:14:37 INFO - mozinfo == 0.9 19:14:37 INFO - mozleak == 0.1 19:14:37 INFO - mozlog == 3.4 19:14:37 INFO - moznetwork == 0.27 19:14:37 INFO - mozprocess == 0.23 19:14:37 INFO - mozprofile == 0.28 19:14:37 INFO - mozrunner == 6.12 19:14:37 INFO - mozscreenshot == 0.1 19:14:37 INFO - mozsystemmonitor == 0.3 19:14:37 INFO - moztest == 0.7 19:14:37 INFO - mozversion == 1.4 19:14:37 INFO - psutil == 3.1.1 19:14:37 INFO - requests == 1.2.3 19:14:37 INFO - wsgiref == 0.1.2 19:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:37 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')]} 19:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:37 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')]} 19:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:37 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:37 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 19:14:37 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 19:14:37 INFO - Using env: {'DISPLAY': ':0', 19:14:37 INFO - 'HOME': '/home/cltbld', 19:14:37 INFO - 'LANG': 'en_US.UTF-8', 19:14:37 INFO - 'LANGUAGE': 'en_US:en', 19:14:37 INFO - 'LOGNAME': 'cltbld', 19:14:37 INFO - 'MAIL': '/var/mail/cltbld', 19:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:37 INFO - 'MOZ_NO_REMOTE': '1', 19:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:37 INFO - 'NO_EM_RESTART': '1', 19:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:37 INFO - 'PWD': '/builds/slave/test', 19:14:37 INFO - 'SHELL': '/bin/bash', 19:14:37 INFO - 'SHLVL': '1', 19:14:37 INFO - 'TERM': 'linux', 19:14:37 INFO - 'TMOUT': '86400', 19:14:37 INFO - 'USER': 'cltbld', 19:14:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:37 INFO - '_': '/tools/buildbot/bin/python'} 19:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 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)) 19:14:37 INFO - Cleaning up... 19:14:37 INFO - Return code: 0 19:14:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:14:37 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')]} 19:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:37 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')]} 19:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:37 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 0x7fdc3ca81f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', '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 19:14:37 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 19:14:37 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 19:14:37 INFO - Using env: {'DISPLAY': ':0', 19:14:37 INFO - 'HOME': '/home/cltbld', 19:14:37 INFO - 'LANG': 'en_US.UTF-8', 19:14:37 INFO - 'LANGUAGE': 'en_US:en', 19:14:37 INFO - 'LOGNAME': 'cltbld', 19:14:37 INFO - 'MAIL': '/var/mail/cltbld', 19:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:37 INFO - 'MOZ_NO_REMOTE': '1', 19:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:37 INFO - 'NO_EM_RESTART': '1', 19:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:37 INFO - 'PWD': '/builds/slave/test', 19:14:37 INFO - 'SHELL': '/bin/bash', 19:14:37 INFO - 'SHLVL': '1', 19:14:37 INFO - 'TERM': 'linux', 19:14:37 INFO - 'TMOUT': '86400', 19:14:37 INFO - 'USER': 'cltbld', 19:14:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:37 INFO - '_': '/tools/buildbot/bin/python'} 19:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:14:38 INFO - Cleaning up... 19:14:38 INFO - Return code: 0 19:14:38 INFO - Running post-action listener: _resource_record_post_action 19:14:38 INFO - Running post-action listener: _start_resource_monitoring 19:14:38 INFO - Starting resource monitoring. 19:14:38 INFO - [mozharness: 2016-09-17 02:14:38.126978Z] Finished create-virtualenv step (success) 19:14:38 INFO - [mozharness: 2016-09-17 02:14:38.127466Z] Running install step. 19:14:38 INFO - Running pre-action listener: _resource_record_pre_action 19:14:38 INFO - Running main action method: install 19:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:38 INFO - Reading from file tmpfile_stdout 19:14:38 INFO - Detecting whether we're running mozinstall >=1.0... 19:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:14:38 INFO - Reading from file tmpfile_stdout 19:14:38 INFO - Output received: 19:14:38 INFO - Usage: mozinstall [options] installer 19:14:38 INFO - Options: 19:14:38 INFO - -h, --help show this help message and exit 19:14:38 INFO - -d DEST, --destination=DEST 19:14:38 INFO - Directory to install application into. [default: 19:14:38 INFO - "/builds/slave/test"] 19:14:38 INFO - --app=APP Application being installed. [default: firefox] 19:14:38 INFO - mkdir: /builds/slave/test/build/application 19:14:38 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'] 19:14:38 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 19:14:55 INFO - Reading from file tmpfile_stdout 19:14:55 INFO - Output received: 19:14:55 INFO - /builds/slave/test/build/application/firefox/firefox 19:14:55 INFO - Running post-action listener: _resource_record_post_action 19:14:55 INFO - [mozharness: 2016-09-17 02:14:55.380926Z] Finished install step (success) 19:14:55 INFO - [mozharness: 2016-09-17 02:14:55.381172Z] Running run-tests step. 19:14:55 INFO - Running pre-action listener: _resource_record_pre_action 19:14:55 INFO - Running main action method: run_tests 19:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:14:55 INFO - Python 2.7.3 19:14:55 INFO - Return code: 0 19:14:55 INFO - grabbing minidump binary from tooltool 19:14:55 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')]} 19:14:55 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 0x2653930>, '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 0x2654830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:14:55 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 19:14:55 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 19:14:55 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 19:14:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:14:55 INFO - Return code: 0 19:14:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:14:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:14:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:14:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:14:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:14:55 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://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 19:14:55 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://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 19:14:55 INFO - Using env: {'DISPLAY': ':0', 19:14:55 INFO - 'HOME': '/home/cltbld', 19:14:55 INFO - 'LANG': 'en_US.UTF-8', 19:14:55 INFO - 'LANGUAGE': 'en_US:en', 19:14:55 INFO - 'LOGNAME': 'cltbld', 19:14:55 INFO - 'MAIL': '/var/mail/cltbld', 19:14:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:14:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:55 INFO - 'MOZ_NO_REMOTE': '1', 19:14:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:14:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:55 INFO - 'NO_EM_RESTART': '1', 19:14:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:55 INFO - 'PWD': '/builds/slave/test', 19:14:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:14:55 INFO - 'SHELL': '/bin/bash', 19:14:55 INFO - 'SHLVL': '1', 19:14:55 INFO - 'TERM': 'linux', 19:14:55 INFO - 'TMOUT': '86400', 19:14:55 INFO - 'USER': 'cltbld', 19:14:55 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460', 19:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:55 INFO - '_': '/tools/buildbot/bin/python'} 19:14:55 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://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 19:14:55 INFO - mozversion application_buildid: 20160916163001 19:14:55 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 19:14:55 INFO - mozversion application_display_name: Nightly 19:14:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:14:55 INFO - mozversion application_name: Firefox 19:14:55 INFO - mozversion application_remotingname: firefox 19:14:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:14:55 INFO - mozversion application_vendor: Mozilla 19:14:55 INFO - mozversion application_version: 51.0a1 19:14:55 INFO - mozversion platform_buildid: 20160916163001 19:14:55 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 19:14:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:14:55 INFO - mozversion platform_version: 51.0a1 19:14:55 INFO - using testdate: 1474078495 19:14:55 INFO - actual date: 1474078495 19:14:55 INFO - starting webserver on 'localhost:44241' 19:14:55 INFO - SUITE-START | Running 2 tests 19:14:55 INFO - TEST-START | dromaeo_css 19:14:55 INFO - operating with platform_type : linux_ 19:14:55 INFO - Initialising browser for dromaeo_css test... 19:14:55 INFO - TEST-INFO | started process 7113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwJuLAd/profile http://localhost:44241/getInfo.html) 19:15:00 INFO - PROCESS | 7113 | __metrics Screen width/height:1600/1200 19:15:00 INFO - PROCESS | 7113 | colorDepth:24 19:15:00 INFO - PROCESS | 7113 | Browser inner width/height: 1024/697 19:15:00 INFO - PROCESS | 7113 | __metrics 19:15:00 INFO - PROCESS | 7113 | 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" 19:15:00 INFO - PROCESS | 7113 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:01 INFO - PROCESS | 7113 | console.error: PushService: 19:15:01 INFO - PROCESS | 7113 | stateChangeProcessEnqueue: Error transitioning state 19:15:01 INFO - PROCESS | 7113 | UnknownError 19:15:02 INFO - TEST-INFO | 7113: exit 0 19:15:02 INFO - Browser initialized. 19:15:02 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:02 INFO - TEST-INFO | started process 7198 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwJuLAd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:14 INFO - PROCESS | 7198 | RSS: Main: 176844800 19:15:14 INFO - PROCESS | 7198 | 19:15:37 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:15:52 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:16:02 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:16:05 INFO - PROCESS | 7198 | 1474078565869 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] 19:16:08 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 19:16:10 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:16:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 19:16:19 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:16:22 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.15005 seconds. 19:16:32 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 19:16:43 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 19:16:47 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.11672 seconds. 19:16:49 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:16:51 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:16:58 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:17:25 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:17:27 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:17:33 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 19:17:35 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:17:54 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 19:18:01 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:18:02 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:18:05 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 19:18:08 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 19:18:11 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 19:18:15 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44241/tests/dromaeo/cssquery-ext.html) 19:18:16 INFO - PROCESS | 7198 | RSS: Main: 307929088 19:18:16 INFO - PROCESS | 7198 | 19:18:26 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:18:35 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:18:51 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:19:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 19:19:18 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:19:28 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:19:40 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:19:54 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:20:02 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:20:18 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:20:20 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:20:36 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:20:40 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:20:43 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:20:47 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:20:52 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:21:11 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:21:17 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:21:19 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-ext.html (next: http://localhost:44241/tests/dromaeo/cssquery-jquery.html) 19:21:20 INFO - PROCESS | 7198 | RSS: Main: 279990272 19:21:20 INFO - PROCESS | 7198 | 19:21:23 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 19:21:24 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:21:28 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:21:36 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:21:44 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:00 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:02 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:22:08 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:14 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:22:29 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:45 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:22:53 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 19:23:07 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:23:15 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:23:20 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:23:23 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:23:25 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:23:37 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:23:59 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:24:22 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44241/tests/dromaeo/cssquery-mootools.html) 19:24:22 INFO - PROCESS | 7198 | RSS: Main: 257814528 19:24:22 INFO - PROCESS | 7198 | 19:24:26 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:24:34 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:24:38 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:01 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:07 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:25:09 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:25:32 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:25:39 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:47 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:49 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:26:24 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:32 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:26:54 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:27:02 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:27:06 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:27:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:27:21 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 19:27:23 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:27:24 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44241/tests/dromaeo/cssquery-prototype.html) 19:27:25 INFO - PROCESS | 7198 | RSS: Main: 214953984 19:27:25 INFO - PROCESS | 7198 | 19:27:28 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 19:27:40 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:27:42 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:27:47 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 19:28:15 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:28:22 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:28:44 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:29:03 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:29:15 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:17 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:29:20 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:29:27 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:29:33 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:29:45 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:29:51 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:55 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:30:01 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:03 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:30:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 19:30:14 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:30:21 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:30:28 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44241/tests/dromaeo/cssquery-yui.html) 19:30:28 INFO - PROCESS | 7198 | RSS: Main: 215785472 19:30:28 INFO - PROCESS | 7198 | 19:30:34 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:30:36 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:43 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:30:46 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:31:21 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:31:25 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:31:34 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:31:36 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:31:52 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:31:58 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:32:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 19:32:14 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:32:26 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:32:28 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:32:44 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:32:46 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:32:50 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:32:57 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:33:12 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:33:15 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:33:21 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:33:29 INFO - PROCESS | 7198 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:33:31 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:44241/tests/dromaeo/cssquery-yui.html (next: http://localhost:44241/tests/dromaeo/cssquery-dojo.html) 19:33:31 INFO - PROCESS | 7198 | RSS: Main: 306118656 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | __start_tp_report 19:33:31 INFO - PROCESS | 7198 | _x_x_mozilla_page_load 19:33:31 INFO - PROCESS | 7198 | _x_x_mozilla_page_load_details 19:33:31 INFO - PROCESS | 7198 | |i|pagename|runs| 19:33:31 INFO - PROCESS | 7198 | |0;dojo.html;3011.8042327248727;3038.5745995560624;3044.619422572178;3066.4940284853;3092.180572148381;4984.327115839361;5061;5184.092783762841;5268.998890210862;5269.789208431668;5111.923321150186;5188.766505507252;5240.502152295526;5244.449332820056;5363.919541206885;4667.6965997210245;4689.648276379268;4722.268048452478;4748.477667456577;4797.760111994404;4664.743439110847;4732.503087175849;4774.785134668949;4794.424669039703;4869.488703686097;4742.478327383985;4748.928766068511;4797;4830.951690483085;4850.345203397549;4598.218302888517;4640.327152562893;4645.581897629196;4675.929861052087;4701.905961880743;4637.930431043537;4639.280911458729;4644.790984405709;4730.455997560291;4737.076270127326;5144.845654630367;5172.922406163911;5202.947970520284;5221.2951251580935;5242.135047717122;5250.107481728115;5271.341082364705;5556.361018482886;5575.609707320489;5578.832635020955;5507.311586051744;5523.751431185607;5524.475174858422;5567.359753628303;5568.9443105568835;5179.948200518022;5198.818041368571;5205.375354957429;5212.322398088224;5258.8948221036135;5416.268803711528;5474.096774032319;5624.746886390122;5625.38120806711;5636.04187288162;5354.678719276856;5437.972810135924;5448.536874365644;5473.042217611902;5533.3083364579425;5532.585056120726;5533.418991005967;5541.030319694038;5551.944480555224;5553.805616803474;5308.708021058879;5529.004779139794;5530.170474428868;5536.972315138399;5595.916061259084;5326.201069839478;5362.410134885159;5492.945070549244;5534.584906132056;5539.556835453154;4279.807408666617;4320.416743739618;4320.611144996902;4321.783910804447;4372.060007098489;3872.5933776953016;3885.6891448684037;3904.39481880309;3930.8034598269974;3968.8015599220503;5085.9745701271995;5147.536721695065;5152.613553983466;5180.663256888365;5314.73426328682;5461.208124821878;5461.508464238238;5464.23500709901;5517.503424691797;5528.723563821793;5206.401263854706;5210.166373380241;5210.557102646318;5213.765380557884;5269;4676.368690226844;4730.124926888537;4739.66822322441;4739.834105806281;4745.1221524018165;5666.235058267163;5698.857528561819;5701.258836351248;5767.971160144256;5776.711164441761;5403.9459605403445;5509.944900550943;5512.007838588932;5514.035043867227;5523.613347065828;5529.944700552943;5582.748776304995;5672.290963629546;5706.714664266853;5708.429157084258;5392.622516423813;5413.512783849473;5424.457554244544;5446.400895901527;5506.5870059744875;5523.861903452445;5533.2253484513;5535.335759708861;5538.667679939055;5539.307586551681;5273.156294992783;5385.92281543685;5395.757190926339;5395.86510337229;5454.809081682122;5293;5331.306930099062;5366.919496207482;5393.757280922447;5461.15352120422;5282.630215884851;5327.387350454747;5339.919901201408;5341.599380046434;5348.117560602457;5360.383555891122;5402.243685884058;5416.025115479251;5424.775291215592;5492.80775172867;5219.112750832292;5306.097963346163;5325.946740532545;5405.837824865261;5435.592330575302 19:33:31 INFO - PROCESS | 7198 | |1;ext.html;549.3226129889138;582.1442479555055;588.1031427073713;598.583984131029;604.1360853978808;27600.585991210122;28114.578281325772;29745.40509189815;29780.979567758346;43117.35323970135;41697;44715.552844471546;44752;44828;44884.775576122076;8872.423292485984;9040.864387034168;9058.547072646374;9059;9060.864087038699;8213.630386632616;8414.410991230616;8937.240334571568;9035.141661542142;9039.548022598876;10701.090407315349;10973.23187376884;10975.890241097606;11279.37963412012;11306.547738090467;10984.395858227794;11040.061594764455;11046.116310695124;11053.28153670013;11062.612808551663;10867.728306792314;10916;10924.836127458095;10930.070943969773;10932.344059356385;41549.37675934833;41603.37594936079;41768.582314176776;41813;42014.57985420168;3249.5125731140283;3295.060907641335;3448.4310088835564;3663.8900832975055;3732.6267373262726;31416;31619.683803161905;32259.19352016195;32314;34287.485687714456;4424.734515929054;4454.33185022246;4476.194285028693;4616.722996620214;4657.115148121846;344857;350269;350300;350477;351835;59357.70321148367;59730.10404843931;59775;59779.402205977385;59870.401295986485;36254.8187259062;37410.43884341737;37456;39070.80464597716;39350.40974385387;15551.144687042095;15715;15725.05649661024;15750.9212453937;15754.10950685925;10597.470126493645;10606.575736970435;10607.681769546925;10609.946950265354;10666.093382062456;13517.932410337886;13798.862011379757;18875.150618222215;19314.016667825614;19502.43980481558;6961.65191740411;6965.930340696629;7081.539699919592;7266.418686505067;7325.597092159983;858.4420126917554;953.7758626722715;979.9608015679436;1055.36677993205;1108.7560736637927;31689.366212675617;31740.04779856608;32019.199520012186;32037.67962320347;32683.509747353808;1033.8988976739747;1052.2266134391216;1065.4033741105006;1123.7078359626555;1144.2733863996423;15994.76007859883;16961.321547138225;17014.659706805796;17047.91476042612;17048.403305884236;486453;490521;490526;491035;491387;58982.70508647515;59797.40202597919;59981;60204.698976504835;60217.397826022054;1304.6999190186311;1324.635725175565;1340.9865901341252;1346.6902612399008;1370.6710389506645;29457.55813662754;31007.379852403275;31092.222694431915;31256.843715781277;31320.21699457532;51575.484245157066;51827.2225916604;51843.740781295855;51862.740686296325;51896.22155667743;405820;407396;407474;407992;408465;27102.322441938322;28681.28296792597;28689.28276793097;28755.568666470437;30236.84881575578;37696.43455348227;39493.80253098831;40593;47751;49367.25949110838;41469;42152.40815266843;42341.91758522643;42850.78574607107;42968.570314296456;38941.415878761276;40381.798091009354;41661.375079373225;42251.57748422476;43783.562164377945;41085.178296434504;41554.58445415507;42405.1518969625;42608.78695606502;42615.57384426116;31482.52776208313;35609.46585801267;36978.26043479169;38377.232455350175;39864.800675996434;27841.303967400978;28099.71900280971;28446.715532844406;28504.714952850205;28527.572086418306 19:33:31 INFO - PROCESS | 7198 | |2;jquery.html;396.23724330663464;398.19763177197933;401.23164140670616;406.78440426573917;418.28055744120104;12976.37392122015;17850.464486065408;18074.277028918845;19579.216831326747;19602.705959410603;14804.111753294783;15189.54431367058;17908.641827163483;18088.54778630535;18819.43541693748;5300.628955973083;5675.943240567603;5717.9714101429645;5725.7995970141055;5727.599068065237;5483.369412517552;5640.971795141039;5718.857028574298;5731;5731.226284451587;5589.692566908819;5648.378678345399;5692.316921969369;5695.174199741035;5695.971520142414;5410.486003829669;5611.158326251054;5632.802851900185;5634.04221282383;5639;5067.214581739836;5069.214271787879;5072.112380333427;5072.568831649314;5072.6195535335;21568.676469852966;21605.891970540204;22221.777782222132;22363.55272894549;22458;2074.4502706782714;2266.6826644269813;2348.694669692929;2777.5139350613763;2993.6079722928944;9807.803843923153;10436.478176091165;10440.634577789817;14237;14297.857021429756;1877.915493802782;1878.1736036144052;1891.4133226924653;1962.8707603525893;1977.2980591889816;75979.08355579509;79340;79975.6001219996;80822.59588702078;81190;10886.564537418415;10923;10960.342379457099;11054.723631909106;11354.829677554679;15690.215489225493;15709.135997520247;15714.057156570643;15735.763963540556;15740.212989350715;6795.93204067953;7148.964255178691;7538.396928245727;7597.810054748676;7761.068671759425;7482.812929676693;7521.8871716923195;7627.771166865003;7734.6132693366235;7767.300942915052;4570.65720070996;4779.211430114062;4781.474037855833;4875.609951203895;4929.679570827956;1765.66161986513;1901.6481950839138;1949.5905859769323;2009.3770931011434;2019.2024150460486;849.8215374771244;883.8895138107737;931.7484279244566;953.1516949914582;976.9936964926137;17499.38752143669;17686.734698979526;18504.814951850578;18827.435176944713;19008.144633491527;881.6341218394423;926.2682480840195;934.7476181430976;939.4081728511131;1004.0561871840592;9686.564104615309;10222.488875556193;10291.279610427198;10340.482975851177;10360.844587331196;450846;451226;469997;470129;478301;93510.53244733869;95551.52224238834;97151;97522;99785.50536236507;981.1611072532882;1008.5814387029448;1039.111559616517;1051.9263651544318;1052.936823790575;16096.195190240674;16866.66266674684;17220;17636.29454821844;17823.10884455798;41450;42106.15787684168;42934.57065429306;43108.35337470003;43140;59149.70425147847;59621.403785961586;59739.40260597512;60841.391586085345;61100;6341.397567231105;8146.389020823343;8182.83634327322;8460.450070745383;8810.603522841615;11386.373749443861;12852.614421567368;12854.357282136043;12864.935675321563;13250.602481925558;10755.48269726701;11266.436678165896;11464.484098215768;11579.247348922294;11638.941805290919;11213.943930280297;11275.15436342294;11332;11346.432678366215;11395.943020285176;11016.724581885197;11126.833097503706;11563.6530904073;11595.130365222474;11681.532738690357;8541.487510749375;8612.65549378018;8616.913830861611;8764.737057888273;8976.435862014112;9497.715068547956;11534.711632209262;11678.941605291919;11813;11852.644420667393 19:33:31 INFO - PROCESS | 7198 | |3;mootools.html;1468.3759402254043;1483.9777403338949;1629.6251862071724;1746.0833062642118;1770.9203085861136;8094.109647938722;8363.665453381858;8788.472691638506;9586.376885502437;9593.232541396688;8865.246454051397;9183.678571250008;9220.815583688322;9489;9490.050994900506;2413.770691784282;2439.902044080171;2441;2448.3879030242438;2461.6061430171176;2066.111572024029;2136.8824714640687;2368.39605900496;2385.0817435287368;2423.9394015149587;2341.637046257833;2417.5527527407485;2423.333583264599;2437.4881274932286;2441.5727247731666;2456.5332586808627;2467.0255249176566;2467.629855521677;2484.7266800651914;2497.7627125423055;2402.7236867760257;2409.578323793329;2415.384077060356;2420.9757902420924;2450.6446565248116;9353.719388418358;9379.467543763214;9486.667966621135;9505.714828555154;9506;5382.354117505885;5527.309086364205;5537.197106419547;5539.196816461631;5539.362973258087;3375.6961873431264;3485.4074807282823;3541.9645803541894;3550.9644903551152;3557.5197348358156;1139.7834411461795;1180.8110702287593;1214.5384753793503;1221.6335099470123;1227.4537830665358;13246.40391182399;13253.204807711472;13294.268815215159;13297.86702132976;13306.467741290337;4852.1508735971065;4942.604591632661;5036.6726162799305;5138.152204886227;5208.765605547759;10211.183105351554;10379.273450858518;10388.220883433622;10393.220508461896;10400.375977441226;5603.3836278009385;5603.971980140073;5606.831795046155;5609.354924183771;5616.213730077792;5029.321041659329;5120.61595380348;5147.124988751922;5219.451957544479;5231.241469986907;3888.5528164260904;3927.351986922183;3944.447777311179;4025.798710064485;4026.476558047435;1094.2668412163869;1134;1158.31659321001;1175.623800383886;1177.604538621736;897.9146981036791;938.5354249646468;940.8521603643544;944.8063147054837;945.8581212818133;5884.735186916599;5940.673262970492;5995.580309378389;6167.96916015426;6245.0632405139595;939.6711151097198;952.2144231009445;961.5144352102284;995.8257304971601;1002.6039714312806;4002.3396139636634;4238.131183107455;4292.720973136736;4423.314386270134;4434.312681534368;11306.886931130748;11613.6515904523;11701.29792212453;11705.414729263503;11716.531338746354;11606.419679016015;11607.071434285237;11629.88370116305;11639.534418623329;11646.534138634359;8124.471909325875;8539.231469167804;8548.401611887108;8550.059493455716;8812.51531165792;6121;6212.347703491068;6345.936540634534;6384.712687929147;6466.482681385572;10709.303895246783;10821.242513024014;10844.295120817122;11070.335779853234;11090.279131856403;10829.18781091437;10861.185411094042;11193.160512961398;11246.943765281121;11278.718032049264;8122.472039317426;8304.04503482083;8394.832103357776;8395.790105247417;8408.159184081544;8603.354748393771;8621.396502244783;8709.390342675952;8710.869336959824;8713.91286087131;8152.714654987047;8156.143604921398;8178.754637360888;8192.713255036046;8422.620982055945;7820.882686759589;7909.406794490321;8142.592870356577;8178.263956243966;8233.135520770473;7192.388646965067;7269.709211631475;7275.563466192046;7331.266873312626;7492.026036615205;5264.2630031794815;5279.920801188062;5294.179402192666;5305.9469405307;5407.134858422633;5200.323957885451;5279.297816611378;5309.601779866448;5414.3232095988005;5540.088669409048 19:33:31 INFO - PROCESS | 7198 | |4;prototype.html;335.5051299333483;337.56959876157913;341.60544571020466;349.0488419058067;350.07405412683437;5281.656692315001;5300.89398212036;5329.227262047001;5348.73256337183;5414.702191379482;5291.973540132303;5327.147656374981;5345.7594408251625;5376.843978544612;5381.165919282517;4015.7992100394936;4141.275276826558;4149.5228048774325;4182.33082706767;4207.326827707568;4168.916081818718;4181.979090104561;4202.348635961431;4235.83056677734;4239.72808157553;4133.627973482386;4143.212789569987;4156.729812562189;4172.144710334374;4180.122174343376;3915.530136383638;3923;4159.5216550096675;4179.561146079648;4212.178625168093;3696.8706095286807;3699.5952630790202;3705.370087085202;3708.629137086297;3752.3808571585655;5167.534921857014;5181.041507321158;5194.402643695986;5195.454477279907;5253.816116435944;4415.735055896656;4647.512011238805;4687.765611719435;4819.542143496362;4821.614270858358;5495.670259784426;5506.449355064502;5511.58663100269;5513.393526712059;5520.199571062173;3940.389239667856;3956.3076461619103;3960.1881614269005;3965.306071437487;3977.423273625308;5450.345958484968;5680.5455563554815;5707.68607726579;5732.914006289909;5750.79872204475;4088.100617864065;4110.17796440713;4111.835526578963;4115.670746340285;4117.835286588563;4173.269677806372;4173.269677806372;4184.811683474251;4187.602177793105;4206.8737937861915;4508;4756.7145971241835;4769.332293478916;4774.641901857305;4786.377770889762;5567.053600887859;5586.804461843816;5592.161175823578;5594.440555944373;5594.496495315359;1419.2052450627682;1446.9565913022627;1448.8696017358488;1451.9346629401703;1455.505128256398;866.8699695045667;921.4747593871429;928.537553353471;943.2218419803689;944.2163004706077;5122.564582010496;5262.342207224097;5275.340582427197;5442.156465747816;5453.4273901240595;5736.627119237236;5740.397258287903;5741.368449470555;5761.366249712528;5764.769409223668;5210.374755029421;5407.324084489439;5423.620346575781;5432.728363581805;5442.482964118402;3012.3372857971212;3044.588980487606;3051.4965030770118;3052.9925124708793;3076.138681169276;5876.294844618673;5882.558808089325;5886.293644762571;5930.9406905931255;5938.683913273827;5629.4652008059165;5792.536597072224;5812.210139133155;5827.213326200994;5831.650100993869;5860.677662728591;5863.912041319383;5873.119032145127;5874.735636896265;5909.970450147808;5343.9465605345;5605.094267948917;5617.634853734494;5666.405027472056;5700.486956173964;5592.412796656293;5605.5515558756015;5605.831825045255;5606.747696353756;5617.382087970402;5829.300483941955;5831.650100993954;5842.211301474246;5845.152452894392;5852.800175964002;3961.3956347679236;4068.552459229423;4074.307367747431;4081.326581114202;4093.6725061995567;5620.213170156099;5636.9436305638055;5644.4195625225;5649.435056494318;5655.406182350958;5509.201165830852;5515.310586176728;5560.860978475571;5561.4716601922755;5575.9163612545035;5321.36143662763;5493.972530137484;5509.917351239815;5543.861403464945;5561.833145005657;5433.565314774887;5474.206240095244;5480.506754392124;5493.340799104133;5498.835034948958;5335.678979643867;5373.623846330719;5478.616496845182;5548.528375088163;5592.832215033555;5471.370792358771;5480.451954804487;5485.8182254293215;5490.258815059916;5491.533219676372 19:33:31 INFO - PROCESS | 7198 | |5;yui.html;697.947653925955;724.3589423360326;725.89663711159;754.7999780058284;781.1446466119601;14754.852451475483;15532;15739.921300393511;15752.448664296766;15754.369825206992;13285.40215690294;13320.866791332084;13346.132501387403;13356.13185142965;13508.392122354493;650.9088727578143;666.7799626123392;788.0779487999026;790.9446338756289;801.8276070644811;791.9049714034323;797.7168105322614;800.4836880212259;802.0455657767268;803.7990502374406;841.0664162779341;843.3421930893882;848.2154007543029;848.9533075680835;849.023622833743;669.5714742564787;683.5761827666829;829.8257365953157;848.2365870716343;848.3086284677998;611.4405319132987;617.2747022248884;617.2808677890253;617.478230894893;617.57387402692;14885.627859303495;14931.626709332353;14949.850501494955;14963.326650300654;14965.102093874402;13678.452861885613;13858.376373063136;14058.648533786634;14149.434022639081;14530.636734081627;12831;12978.675533111653;13201.801972970317;13478.258695771729;13738.931305343509;4311.245532031882;4394;4401.295792673157;4413.815161495048;4582.541745825424;73941;75338.62330688366;76699;76937.23062769357;77027;10989.780204395867;11178.776424471465;11217.775644487065;11391.886081139082;11946.462409191607;21422.89288553547;21643.567128657654;21687.45781355479;21725.348239552837;21730;7670.463067585216;7797.337226335711;7835.138134805167;7910.7231246906085;8002.199780022068;4212.410262563244;4224.176285624335;4362.105768317488;4394.340848872631;4531.864044078683;3371.8145501997624;3483.111806489329;3484.233468636896;3485.19840436701;3486.738494612914;571.8170185540587;580.5239703443136;593.2021431175062;595.7884950842475;600.2946537818046;506.5263978180413;519.966202196856;579.2990481517411;585.819603186382;640.7565125252468;9391.295652826066;9576.616935322649;9594.856077158849;10316.742081448025;10702.555155054124;680.251723104581;722.6856317501904;722.8337482379083;738.6159197217524;764.3808515102673;4612.377329060534;4887.511248875084;4901.460839307673;4922.286268491049;4945.653804233669;100582;114988;120965;121675;121884;96393;99459.50270248747;100344;101508;106352;83738;86012.56993715116;86020.56989715011;86565;90949.09050909539;8897.743686805747;9148.542572871464;9177.954110229406;9201.953990230006;9328.347015709107;36883.63116368802;48920.26619600638;49712.50287497223;50302.49697502978;50590.24114638357;68746.65626671835;76943.61528192324;77241.61379193068;78014;79468.60265698635;11857;11990.820137697767;12056.879431205576;12102.818457722966;12820.423080961193;13369;13397.53108641193;13586.524471643444;13684.794728078889;14520.709585808436;11905.702357441134;12447.190932589352;12498.008126842642;13134.211947283193;13482.730345393233;12550.43523041447;12593.370331483206;12666;12915.741685166431;12934.741305174031;12407.190406143187;12707.237565746085;12869.420876060412;13156.60530184096;13423.194608323533;10120.443375614117;10293;10476.476176191007;10723.463826808473;10918.836217456903;9200.861987070066;9820.165285950774;10210.438425886647;10336.948315258676;10377.84433233487 19:33:31 INFO - PROCESS | 7198 | __end_tp_report 19:33:31 INFO - PROCESS | 7198 | __start_cc_report 19:33:31 INFO - PROCESS | 7198 | _x_x_mozilla_cycle_collect,1060 19:33:31 INFO - PROCESS | 7198 | __end_cc_report 19:33:31 INFO - PROCESS | 7198 | __startTimestamp1474079611977__endTimestamp 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | ------- Summary: start ------- 19:33:31 INFO - PROCESS | 7198 | Number of tests: 6 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | [#0] dojo.html Cycles:165 Average:5134.87 Median:5325.95 stddev:551.86 (10.4%) stddev-sans-first:527.82 19:33:31 INFO - PROCESS | 7198 | Values: 3011.8 3038.6 3044.6 3066.5 3092.2 4984.3 5061.0 5184.1 5269.0 5269.8 5111.9 5188.8 5240.5 5244.4 5363.9 4667.7 4689.6 4722.3 4748.5 4797.8 4664.7 4732.5 4774.8 4794.4 4869.5 4742.5 4748.9 4797.0 4831.0 4850.3 4598.2 4640.3 4645.6 4675.9 4701.9 4637.9 4639.3 4644.8 4730.5 4737.1 5144.8 5172.9 5202.9 5221.3 5242.1 5250.1 5271.3 5556.4 5575.6 5578.8 5507.3 5523.8 5524.5 5567.4 5568.9 5179.9 5198.8 5205.4 5212.3 5258.9 5416.3 5474.1 5624.7 5625.4 5636.0 5354.7 5438.0 5448.5 5473.0 5533.3 5532.6 5533.4 5541.0 5551.9 5553.8 5308.7 5529.0 5530.2 5537.0 5595.9 5326.2 5362.4 5492.9 5534.6 5539.6 4279.8 4320.4 4320.6 4321.8 4372.1 3872.6 3885.7 3904.4 3930.8 3968.8 5086.0 5147.5 5152.6 5180.7 5314.7 5461.2 5461.5 5464.2 5517.5 5528.7 5206.4 5210.2 5210.6 5213.8 5269.0 4676.4 4730.1 4739.7 4739.8 4745.1 5666.2 5698.9 5701.3 5768.0 5776.7 5403.9 5509.9 5512.0 5514.0 5523.6 5529.9 5582.7 5672.3 5706.7 5708.4 5392.6 5413.5 5424.5 5446.4 5506.6 5523.9 5533.2 5535.3 5538.7 5539.3 5273.2 5385.9 5395.8 5395.9 5454.8 5293.0 5331.3 5366.9 5393.8 5461.2 5282.6 5327.4 5339.9 5341.6 5348.1 5360.4 5402.2 5416.0 5424.8 5492.8 5219.1 5306.1 5325.9 5405.8 5435.6 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | [#1] ext.html Cycles:180 Average:57257.76 Median:37433.22 stddev:110980.52 (296.5%) stddev-sans-first:111210.18 19:33:31 INFO - PROCESS | 7198 | Values: 549.3 582.1 588.1 598.6 604.1 27600.6 28114.6 29745.4 29781.0 43117.4 41697.0 44715.6 44752.0 44828.0 44884.8 8872.4 9040.9 9058.5 9059.0 9060.9 8213.6 8414.4 8937.2 9035.1 9039.5 10701.1 10973.2 10975.9 11279.4 11306.5 10984.4 11040.1 11046.1 11053.3 11062.6 10867.7 10916.0 10924.8 10930.1 10932.3 41549.4 41603.4 41768.6 41813.0 42014.6 3249.5 3295.1 3448.4 3663.9 3732.6 31416.0 31619.7 32259.2 32314.0 34287.5 4424.7 4454.3 4476.2 4616.7 4657.1 344857.0 350269.0 350300.0 350477.0 351835.0 59357.7 59730.1 59775.0 59779.4 59870.4 36254.8 37410.4 37456.0 39070.8 39350.4 15551.1 15715.0 15725.1 15750.9 15754.1 10597.5 10606.6 10607.7 10609.9 10666.1 13517.9 13798.9 18875.2 19314.0 19502.4 6961.7 6965.9 7081.5 7266.4 7325.6 858.4 953.8 980.0 1055.4 1108.8 31689.4 31740.0 32019.2 32037.7 32683.5 1033.9 1052.2 1065.4 1123.7 1144.3 15994.8 16961.3 17014.7 17047.9 17048.4 486453.0 490521.0 490526.0 491035.0 491387.0 58982.7 59797.4 59981.0 60204.7 60217.4 1304.7 1324.6 1341.0 1346.7 1370.7 29457.6 31007.4 31092.2 31256.8 31320.2 51575.5 51827.2 51843.7 51862.7 51896.2 405820.0 407396.0 407474.0 407992.0 408465.0 27102.3 28681.3 28689.3 28755.6 30236.8 37696.4 39493.8 40593.0 47751.0 49367.3 41469.0 42152.4 42341.9 42850.8 42968.6 38941.4 40381.8 41661.4 42251.6 43783.6 41085.2 41554.6 42405.2 42608.8 42615.6 31482.5 35609.5 36978.3 38377.2 39864.8 27841.3 28099.7 28446.7 28504.7 28527.6 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | [#2] jquery.html Cycles:180 Average:28206.00 Median:20928.39 stddev:76848.79 (367.2%) stddev-sans-first:77036.00 19:33:31 INFO - PROCESS | 7198 | Values: 396.2 398.2 401.2 406.8 418.3 12976.4 17850.5 18074.3 19579.2 19602.7 14804.1 15189.5 17908.6 18088.5 18819.4 5300.6 5675.9 5718.0 5725.8 5727.6 5483.4 5641.0 5718.9 5731.0 5731.2 5589.7 5648.4 5692.3 5695.2 5696.0 5410.5 5611.2 5632.8 5634.0 5639.0 5067.2 5069.2 5072.1 5072.6 5072.6 21568.7 21605.9 22221.8 22363.6 22458.0 2074.5 2266.7 2348.7 2777.5 2993.6 9807.8 10436.5 10440.6 14237.0 14297.9 1877.9 1878.2 1891.4 1962.9 1977.3 75979.1 79340.0 79975.6 80822.6 81190.0 10886.6 10923.0 10960.3 11054.7 11354.8 15690.2 15709.1 15714.1 15735.8 15740.2 6795.9 7149.0 7538.4 7597.8 7761.1 7482.8 7521.9 7627.8 7734.6 7767.3 4570.7 4779.2 4781.5 4875.6 4929.7 1765.7 1901.6 1949.6 2009.4 2019.2 849.8 883.9 931.7 953.2 977.0 17499.4 17686.7 18504.8 18827.4 19008.1 881.6 926.3 934.7 939.4 1004.1 9686.6 10222.5 10291.3 10340.5 10360.8 450846.0 451226.0 469997.0 470129.0 478301.0 93510.5 95551.5 97151.0 97522.0 99785.5 981.2 1008.6 1039.1 1051.9 1052.9 16096.2 16866.7 17220.0 17636.3 17823.1 41450.0 42106.2 42934.6 43108.4 43140.0 59149.7 59621.4 59739.4 60841.4 61100.0 6341.4 8146.4 8182.8 8460.5 8810.6 11386.4 12852.6 12854.4 12864.9 13250.6 10755.5 11266.4 11464.5 11579.2 11638.9 11213.9 11275.2 11332.0 11346.4 11395.9 11016.7 11126.8 11563.7 11595.1 11681.5 8541.5 8612.7 8616.9 8764.7 8976.4 9497.7 11534.7 11678.9 11813.0 11852.6 19:33:31 INFO - PROCESS | 7198 | 19:33:31 INFO - PROCESS | 7198 | [#3] mootools.html Cycles:180 Average:6096.83 Median:5129.38 stddev:3520.69 (68.6%) stddev-sans-first:3513.39 19:33:32 INFO - PROCESS | 7198 | Values: 1468.4 1484.0 1629.6 1746.1 1770.9 8094.1 8363.7 8788.5 9586.4 9593.2 8865.2 9183.7 9220.8 9489.0 9490.1 2413.8 2439.9 2441.0 2448.4 2461.6 2066.1 2136.9 2368.4 2385.1 2423.9 2341.6 2417.6 2423.3 2437.5 2441.6 2456.5 2467.0 2467.6 2484.7 2497.8 2402.7 2409.6 2415.4 2421.0 2450.6 9353.7 9379.5 9486.7 9505.7 9506.0 5382.4 5527.3 5537.2 5539.2 5539.4 3375.7 3485.4 3542.0 3551.0 3557.5 1139.8 1180.8 1214.5 1221.6 1227.5 13246.4 13253.2 13294.3 13297.9 13306.5 4852.2 4942.6 5036.7 5138.2 5208.8 10211.2 10379.3 10388.2 10393.2 10400.4 5603.4 5604.0 5606.8 5609.4 5616.2 5029.3 5120.6 5147.1 5219.5 5231.2 3888.6 3927.4 3944.4 4025.8 4026.5 1094.3 1134.0 1158.3 1175.6 1177.6 897.9 938.5 940.9 944.8 945.9 5884.7 5940.7 5995.6 6168.0 6245.1 939.7 952.2 961.5 995.8 1002.6 4002.3 4238.1 4292.7 4423.3 4434.3 11306.9 11613.7 11701.3 11705.4 11716.5 11606.4 11607.1 11629.9 11639.5 11646.5 8124.5 8539.2 8548.4 8550.1 8812.5 6121.0 6212.3 6345.9 6384.7 6466.5 10709.3 10821.2 10844.3 11070.3 11090.3 10829.2 10861.2 11193.2 11246.9 11278.7 8122.5 8304.0 8394.8 8395.8 8408.2 8603.4 8621.4 8709.4 8710.9 8713.9 8152.7 8156.1 8178.8 8192.7 8422.6 7820.9 7909.4 8142.6 8178.3 8233.1 7192.4 7269.7 7275.6 7331.3 7492.0 5264.3 5279.9 5294.2 5305.9 5407.1 5200.3 5279.3 5309.6 5414.3 5540.1 19:33:32 INFO - PROCESS | 7198 | 19:33:32 INFO - PROCESS | 7198 | [#4] prototype.html Cycles:180 Average:4681.28 Median:5379.00 stddev:1366.19 (25.4%) stddev-sans-first:1330.52 19:33:32 INFO - PROCESS | 7198 | Values: 335.5 337.6 341.6 349.0 350.1 5281.7 5300.9 5329.2 5348.7 5414.7 5292.0 5327.1 5345.8 5376.8 5381.2 4015.8 4141.3 4149.5 4182.3 4207.3 4168.9 4182.0 4202.3 4235.8 4239.7 4133.6 4143.2 4156.7 4172.1 4180.1 3915.5 3923.0 4159.5 4179.6 4212.2 3696.9 3699.6 3705.4 3708.6 3752.4 5167.5 5181.0 5194.4 5195.5 5253.8 4415.7 4647.5 4687.8 4819.5 4821.6 5495.7 5506.4 5511.6 5513.4 5520.2 3940.4 3956.3 3960.2 3965.3 3977.4 5450.3 5680.5 5707.7 5732.9 5750.8 4088.1 4110.2 4111.8 4115.7 4117.8 4173.3 4173.3 4184.8 4187.6 4206.9 4508.0 4756.7 4769.3 4774.6 4786.4 5567.1 5586.8 5592.2 5594.4 5594.5 1419.2 1447.0 1448.9 1451.9 1455.5 866.9 921.5 928.5 943.2 944.2 5122.6 5262.3 5275.3 5442.2 5453.4 5736.6 5740.4 5741.4 5761.4 5764.8 5210.4 5407.3 5423.6 5432.7 5442.5 3012.3 3044.6 3051.5 3053.0 3076.1 5876.3 5882.6 5886.3 5930.9 5938.7 5629.5 5792.5 5812.2 5827.2 5831.7 5860.7 5863.9 5873.1 5874.7 5910.0 5343.9 5605.1 5617.6 5666.4 5700.5 5592.4 5605.6 5605.8 5606.7 5617.4 5829.3 5831.7 5842.2 5845.2 5852.8 3961.4 4068.6 4074.3 4081.3 4093.7 5620.2 5636.9 5644.4 5649.4 5655.4 5509.2 5515.3 5560.9 5561.5 5575.9 5321.4 5494.0 5509.9 5543.9 5561.8 5433.6 5474.2 5480.5 5493.3 5498.8 5335.7 5373.6 5478.6 5548.5 5592.8 5471.4 5480.5 5485.8 5490.3 5491.5 19:33:32 INFO - PROCESS | 7198 | 19:33:32 INFO - PROCESS | 7198 | [#5] yui.html Cycles:180 Average:20612.09 Median:4407.56 stddev:30238.28 (686.1%) stddev-sans-first:30286.14 19:33:32 INFO - PROCESS | 7198 | Values: 697.9 724.4 725.9 754.8 781.1 14754.9 15532.0 15739.9 15752.4 15754.4 13285.4 13320.9 13346.1 13356.1 13508.4 650.9 666.8 788.1 790.9 801.8 791.9 797.7 800.5 802.0 803.8 841.1 843.3 848.2 849.0 849.0 669.6 683.6 829.8 848.2 848.3 611.4 617.3 617.3 617.5 617.6 14885.6 14931.6 14949.9 14963.3 14965.1 13678.5 13858.4 14058.6 14149.4 14530.6 12831.0 12978.7 13201.8 13478.3 13738.9 4311.2 4394.0 4401.3 4413.8 4582.5 73941.0 75338.6 76699.0 76937.2 77027.0 10989.8 11178.8 11217.8 11391.9 11946.5 21422.9 21643.6 21687.5 21725.3 21730.0 7670.5 7797.3 7835.1 7910.7 8002.2 4212.4 4224.2 4362.1 4394.3 4531.9 3371.8 3483.1 3484.2 3485.2 3486.7 571.8 580.5 593.2 595.8 600.3 506.5 520.0 579.3 585.8 640.8 9391.3 9576.6 9594.9 10316.7 10702.6 680.3 722.7 722.8 738.6 764.4 4612.4 4887.5 4901.5 4922.3 4945.7 100582.0 114988.0 120965.0 121675.0 121884.0 96393.0 99459.5 100344.0 101508.0 106352.0 83738.0 86012.6 86020.6 86565.0 90949.1 8897.7 9148.5 9178.0 9202.0 9328.3 36883.6 48920.3 49712.5 50302.5 50590.2 68746.7 76943.6 77241.6 78014.0 79468.6 11857.0 11990.8 12056.9 12102.8 12820.4 13369.0 13397.5 13586.5 13684.8 14520.7 11905.7 12447.2 12498.0 13134.2 13482.7 12550.4 12593.4 12666.0 12915.7 12934.7 12407.2 12707.2 12869.4 13156.6 13423.2 10120.4 10293.0 10476.5 10723.5 10918.8 9200.9 9820.2 10210.4 10336.9 10377.8 19:33:32 INFO - PROCESS | 7198 | -------- Summary: end -------- 19:33:32 INFO - PROCESS | 7198 | 19:33:32 INFO - TEST-INFO | 7198: exit 0 19:33:32 INFO - TEST-OK | dromaeo_css | took 1117307ms 19:33:32 INFO - TEST-START | kraken 19:33:32 INFO - operating with platform_type : linux_ 19:33:32 INFO - Initialising browser for kraken test... 19:33:32 INFO - TEST-INFO | started process 7506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW9hKu3/profile http://localhost:44241/getInfo.html) 19:33:37 INFO - PROCESS | 7506 | __metrics Screen width/height:1600/1200 19:33:37 INFO - PROCESS | 7506 | colorDepth:24 19:33:37 INFO - PROCESS | 7506 | Browser inner width/height: 1024/697 19:33:37 INFO - PROCESS | 7506 | __metrics 19:33:38 INFO - PROCESS | 7506 | 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" 19:33:38 INFO - PROCESS | 7506 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:33:38 INFO - PROCESS | 7506 | console.error: PushService: 19:33:38 INFO - PROCESS | 7506 | stateChangeProcessEnqueue: Error transitioning state 19:33:38 INFO - PROCESS | 7506 | UnknownError 19:33:39 INFO - TEST-INFO | 7506: exit 0 19:33:39 INFO - Browser initialized. 19:33:39 INFO - Running cycle 1/1 for kraken test... 19:33:39 INFO - TEST-INFO | started process 7590 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW9hKu3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:51 INFO - PROCESS | 7590 | RSS: Main: 172802048 19:33:51 INFO - PROCESS | 7590 | 19:33:54 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44241/tests/kraken/driver.html?testName=audio-beat-detection) 19:33:54 INFO - PROCESS | 7590 | RSS: Main: 232345600 19:33:54 INFO - PROCESS | 7590 | 19:33:57 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44241/tests/kraken/driver.html?testName=audio-dft) 19:33:58 INFO - PROCESS | 7590 | RSS: Main: 217210880 19:33:58 INFO - PROCESS | 7590 | 19:34:01 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44241/tests/kraken/driver.html?testName=audio-fft) 19:34:01 INFO - PROCESS | 7590 | RSS: Main: 297922560 19:34:01 INFO - PROCESS | 7590 | 19:34:04 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44241/tests/kraken/driver.html?testName=audio-oscillator) 19:34:04 INFO - PROCESS | 7590 | RSS: Main: 208203776 19:34:04 INFO - PROCESS | 7590 | 19:34:06 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:07 INFO - PROCESS | 7590 | RSS: Main: 212201472 19:34:07 INFO - PROCESS | 7590 | 19:34:10 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44241/tests/kraken/driver.html?testName=imaging-darkroom) 19:34:10 INFO - PROCESS | 7590 | RSS: Main: 248524800 19:34:10 INFO - PROCESS | 7590 | 19:34:14 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44241/tests/kraken/driver.html?testName=imaging-desaturate) 19:34:14 INFO - PROCESS | 7590 | RSS: Main: 247853056 19:34:14 INFO - PROCESS | 7590 | 19:34:17 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44241/tests/kraken/driver.html?testName=json-parse-financial) 19:34:18 INFO - PROCESS | 7590 | RSS: Main: 247541760 19:34:18 INFO - PROCESS | 7590 | 19:34:19 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:34:20 INFO - PROCESS | 7590 | RSS: Main: 209809408 19:34:20 INFO - PROCESS | 7590 | 19:34:22 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:34:22 INFO - PROCESS | 7590 | RSS: Main: 216530944 19:34:22 INFO - PROCESS | 7590 | 19:34:24 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:34:25 INFO - PROCESS | 7590 | RSS: Main: 221425664 19:34:25 INFO - PROCESS | 7590 | 19:34:28 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:34:28 INFO - PROCESS | 7590 | RSS: Main: 222973952 19:34:28 INFO - PROCESS | 7590 | 19:34:31 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:34:32 INFO - PROCESS | 7590 | RSS: Main: 220905472 19:34:32 INFO - PROCESS | 7590 | 19:34:33 INFO - PROCESS | 7590 | Cycle 1(1): loaded http://localhost:44241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44241/tests/kraken/driver.html?testName=ai-astar) 19:34:34 INFO - PROCESS | 7590 | RSS: Main: 272818176 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | __start_tp_report 19:34:34 INFO - PROCESS | 7590 | _x_x_mozilla_page_load 19:34:34 INFO - PROCESS | 7590 | _x_x_mozilla_page_load_details 19:34:34 INFO - PROCESS | 7590 | |i|pagename|runs| 19:34:34 INFO - PROCESS | 7590 | |0;ai-astar;109;113;111;111;112;112;112;112;112;112 19:34:34 INFO - PROCESS | 7590 | |1;audio-beat-detection;153;124;119;156;120;119;117;124;148;120 19:34:34 INFO - PROCESS | 7590 | |2;audio-dft;160;161;161;163;161;163;162;161;162;162 19:34:34 INFO - PROCESS | 7590 | |3;audio-fft;75;76;75;115;74;77;77;76;100;75 19:34:34 INFO - PROCESS | 7590 | |4;audio-oscillator;82;84;119;83;82;83;103;83;82;108 19:34:34 INFO - PROCESS | 7590 | |5;imaging-gaussian-blur;117;117;117;117;116;117;118;117;117;116 19:34:34 INFO - PROCESS | 7590 | |6;imaging-darkroom;145;146;145;146;145;146;147;146;145;146 19:34:34 INFO - PROCESS | 7590 | |7;imaging-desaturate;95;95;95;94;95;95;94;95;95;94 19:34:34 INFO - PROCESS | 7590 | |8;json-parse-financial;51;50;49;48;49;51;51;50;51;57 19:34:34 INFO - PROCESS | 7590 | |9;json-stringify-tinderbox;60;61;60;60;61;60;59;60;60;59 19:34:34 INFO - PROCESS | 7590 | |10;stanford-crypto-aes;87;88;82;80;78;81;79;86;84;78 19:34:34 INFO - PROCESS | 7590 | |11;stanford-crypto-ccm;130;125;124;135;131;123;137;124;160;136 19:34:34 INFO - PROCESS | 7590 | |12;stanford-crypto-pbkdf2;165;167;164;169;170;171;164;170;169;164 19:34:34 INFO - PROCESS | 7590 | |13;stanford-crypto-sha256-iterative;68;66;67;71;66;66;65;66;64;65 19:34:34 INFO - PROCESS | 7590 | __end_tp_report 19:34:34 INFO - PROCESS | 7590 | __start_cc_report 19:34:34 INFO - PROCESS | 7590 | _x_x_mozilla_cycle_collect,1598 19:34:34 INFO - PROCESS | 7590 | __end_cc_report 19:34:34 INFO - PROCESS | 7590 | __startTimestamp1474079674082__endTimestamp 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | ------- Summary: start ------- 19:34:34 INFO - PROCESS | 7590 | Number of tests: 14 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:1.07 (1.0%) stddev-sans-first:0.60 19:34:34 INFO - PROCESS | 7590 | Values: 109.0 113.0 111.0 111.0 112.0 112.0 112.0 112.0 112.0 112.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:124.00 stddev:15.68 (12.6%) stddev-sans-first:14.25 19:34:34 INFO - PROCESS | 7590 | Values: 153.0 124.0 119.0 156.0 120.0 119.0 117.0 124.0 148.0 120.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 19:34:34 INFO - PROCESS | 7590 | Values: 160.0 161.0 161.0 163.0 161.0 163.0 162.0 161.0 162.0 162.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.50 stddev:13.93 (18.4%) stddev-sans-first:14.54 19:34:34 INFO - PROCESS | 7590 | Values: 75.0 76.0 75.0 115.0 74.0 77.0 77.0 76.0 100.0 75.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:82.50 stddev:13.75 (16.7%) stddev-sans-first:14.20 19:34:34 INFO - PROCESS | 7590 | Values: 82.0 84.0 119.0 83.0 82.0 83.0 103.0 83.0 82.0 108.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 19:34:34 INFO - PROCESS | 7590 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 118.0 117.0 117.0 116.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 19:34:34 INFO - PROCESS | 7590 | Values: 145.0 146.0 145.0 146.0 145.0 146.0 147.0 146.0 145.0 146.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:34:34 INFO - PROCESS | 7590 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:2.45 (4.8%) stddev-sans-first:2.60 19:34:34 INFO - PROCESS | 7590 | Values: 51.0 50.0 49.0 48.0 49.0 51.0 51.0 50.0 51.0 57.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 19:34:34 INFO - PROCESS | 7590 | Values: 60.0 61.0 60.0 60.0 61.0 60.0 59.0 60.0 60.0 59.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:3.74 (4.5%) stddev-sans-first:3.56 19:34:34 INFO - PROCESS | 7590 | Values: 87.0 88.0 82.0 80.0 78.0 81.0 79.0 86.0 84.0 78.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:133.00 stddev:11.03 (8.3%) stddev-sans-first:11.66 19:34:34 INFO - PROCESS | 7590 | Values: 130.0 125.0 124.0 135.0 131.0 123.0 137.0 124.0 160.0 136.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:169.00 stddev:2.83 (1.7%) stddev-sans-first:2.88 19:34:34 INFO - PROCESS | 7590 | Values: 165.0 167.0 164.0 169.0 170.0 171.0 164.0 170.0 169.0 164.0 19:34:34 INFO - PROCESS | 7590 | 19:34:34 INFO - PROCESS | 7590 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:1.96 (3.0%) stddev-sans-first:1.99 19:34:34 INFO - PROCESS | 7590 | Values: 68.0 66.0 67.0 71.0 66.0 66.0 65.0 66.0 64.0 65.0 19:34:34 INFO - PROCESS | 7590 | -------- Summary: end -------- 19:34:34 INFO - PROCESS | 7590 | 19:34:35 INFO - TEST-INFO | 7590: exit 0 19:34:35 INFO - TEST-OK | kraken | took 62374ms 19:34:35 INFO - SUITE-END | took 1179s 19:34:35 INFO - Completed test suite (00:19:40) 19:34:35 INFO - javascript benchmark 19:34:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.8042327248727, 3038.5745995560624, 3044.619422572178, 3066.4940284853, 3092.180572148381, 4984.327115839361, 5061.0, 5184.092783762841, 5268.998890210862, 5269.789208431668, 5111.923321150186, 5188.766505507252, 5240.502152295526, 5244.449332820056, 5363.919541206885, 4667.6965997210245, 4689.648276379268, 4722.268048452478, 4748.477667456577, 4797.760111994404, 4664.743439110847, 4732.503087175849, 4774.785134668949, 4794.424669039703, 4869.488703686097, 4742.478327383985, 4748.928766068511, 4797.0, 4830.951690483085, 4850.345203397549, 4598.218302888517, 4640.327152562893, 4645.581897629196, 4675.929861052087, 4701.905961880743, 4637.930431043537, 4639.280911458729, 4644.790984405709, 4730.455997560291, 4737.076270127326, 5144.845654630367, 5172.922406163911, 5202.947970520284, 5221.2951251580935, 5242.135047717122, 5250.107481728115, 5271.341082364705, 5556.361018482886, 5575.609707320489, 5578.832635020955, 5507.311586051744, 5523.751431185607, 5524.475174858422, 5567.359753628303, 5568.9443105568835, 5179.948200518022, 5198.818041368571, 5205.375354957429, 5212.322398088224, 5258.8948221036135, 5416.268803711528, 5474.096774032319, 5624.746886390122, 5625.38120806711, 5636.04187288162, 5354.678719276856, 5437.972810135924, 5448.536874365644, 5473.042217611902, 5533.3083364579425, 5532.585056120726, 5533.418991005967, 5541.030319694038, 5551.944480555224, 5553.805616803474, 5308.708021058879, 5529.004779139794, 5530.170474428868, 5536.972315138399, 5595.916061259084, 5326.201069839478, 5362.410134885159, 5492.945070549244, 5534.584906132056, 5539.556835453154, 4279.807408666617, 4320.416743739618, 4320.611144996902, 4321.783910804447, 4372.060007098489, 3872.5933776953016, 3885.6891448684037, 3904.39481880309, 3930.8034598269974, 3968.8015599220503, 5085.9745701271995, 5147.536721695065, 5152.613553983466, 5180.663256888365, 5314.73426328682, 5461.208124821878, 5461.508464238238, 5464.23500709901, 5517.503424691797, 5528.723563821793, 5206.401263854706, 5210.166373380241, 5210.557102646318, 5213.765380557884, 5269.0, 4676.368690226844, 4730.124926888537, 4739.66822322441, 4739.834105806281, 4745.1221524018165, 5666.235058267163, 5698.857528561819, 5701.258836351248, 5767.971160144256, 5776.711164441761, 5403.9459605403445, 5509.944900550943, 5512.007838588932, 5514.035043867227, 5523.613347065828, 5529.944700552943, 5582.748776304995, 5672.290963629546, 5706.714664266853, 5708.429157084258, 5392.622516423813, 5413.512783849473, 5424.457554244544, 5446.400895901527, 5506.5870059744875, 5523.861903452445, 5533.2253484513, 5535.335759708861, 5538.667679939055, 5539.307586551681, 5273.156294992783, 5385.92281543685, 5395.757190926339, 5395.86510337229, 5454.809081682122, 5293.0, 5331.306930099062, 5366.919496207482, 5393.757280922447, 5461.15352120422, 5282.630215884851, 5327.387350454747, 5339.919901201408, 5341.599380046434, 5348.117560602457, 5360.383555891122, 5402.243685884058, 5416.025115479251, 5424.775291215592, 5492.80775172867, 5219.112750832292, 5306.097963346163, 5325.946740532545, 5405.837824865261, 5435.592330575302], "value": 5099.906774207745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.3226129889138, 582.1442479555055, 588.1031427073713, 598.583984131029, 604.1360853978808, 27600.585991210122, 28114.578281325772, 29745.40509189815, 29780.979567758346, 43117.35323970135, 41697.0, 44715.552844471546, 44752.0, 44828.0, 44884.775576122076, 8872.423292485984, 9040.864387034168, 9058.547072646374, 9059.0, 9060.864087038699, 8213.630386632616, 8414.410991230616, 8937.240334571568, 9035.141661542142, 9039.548022598876, 10701.090407315349, 10973.23187376884, 10975.890241097606, 11279.37963412012, 11306.547738090467, 10984.395858227794, 11040.061594764455, 11046.116310695124, 11053.28153670013, 11062.612808551663, 10867.728306792314, 10916.0, 10924.836127458095, 10930.070943969773, 10932.344059356385, 41549.37675934833, 41603.37594936079, 41768.582314176776, 41813.0, 42014.57985420168, 3249.5125731140283, 3295.060907641335, 3448.4310088835564, 3663.8900832975055, 3732.6267373262726, 31416.0, 31619.683803161905, 32259.19352016195, 32314.0, 34287.485687714456, 4424.734515929054, 4454.33185022246, 4476.194285028693, 4616.722996620214, 4657.115148121846, 344857.0, 350269.0, 350300.0, 350477.0, 351835.0, 59357.70321148367, 59730.10404843931, 59775.0, 59779.402205977385, 59870.401295986485, 36254.8187259062, 37410.43884341737, 37456.0, 39070.80464597716, 39350.40974385387, 15551.144687042095, 15715.0, 15725.05649661024, 15750.9212453937, 15754.10950685925, 10597.470126493645, 10606.575736970435, 10607.681769546925, 10609.946950265354, 10666.093382062456, 13517.932410337886, 13798.862011379757, 18875.150618222215, 19314.016667825614, 19502.43980481558, 6961.65191740411, 6965.930340696629, 7081.539699919592, 7266.418686505067, 7325.597092159983, 858.4420126917554, 953.7758626722715, 979.9608015679436, 1055.36677993205, 1108.7560736637927, 31689.366212675617, 31740.04779856608, 32019.199520012186, 32037.67962320347, 32683.509747353808, 1033.8988976739747, 1052.2266134391216, 1065.4033741105006, 1123.7078359626555, 1144.2733863996423, 15994.76007859883, 16961.321547138225, 17014.659706805796, 17047.91476042612, 17048.403305884236, 486453.0, 490521.0, 490526.0, 491035.0, 491387.0, 58982.70508647515, 59797.40202597919, 59981.0, 60204.698976504835, 60217.397826022054, 1304.6999190186311, 1324.635725175565, 1340.9865901341252, 1346.6902612399008, 1370.6710389506645, 29457.55813662754, 31007.379852403275, 31092.222694431915, 31256.843715781277, 31320.21699457532, 51575.484245157066, 51827.2225916604, 51843.740781295855, 51862.740686296325, 51896.22155667743, 405820.0, 407396.0, 407474.0, 407992.0, 408465.0, 27102.322441938322, 28681.28296792597, 28689.28276793097, 28755.568666470437, 30236.84881575578, 37696.43455348227, 39493.80253098831, 40593.0, 47751.0, 49367.25949110838, 41469.0, 42152.40815266843, 42341.91758522643, 42850.78574607107, 42968.570314296456, 38941.415878761276, 40381.798091009354, 41661.375079373225, 42251.57748422476, 43783.562164377945, 41085.178296434504, 41554.58445415507, 42405.1518969625, 42608.78695606502, 42615.57384426116, 31482.52776208313, 35609.46585801267, 36978.26043479169, 38377.232455350175, 39864.800675996434, 27841.303967400978, 28099.71900280971, 28446.715532844406, 28504.714952850205, 28527.572086418306], "value": 19708.03980043608, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.23724330663464, 398.19763177197933, 401.23164140670616, 406.78440426573917, 418.28055744120104, 12976.37392122015, 17850.464486065408, 18074.277028918845, 19579.216831326747, 19602.705959410603, 14804.111753294783, 15189.54431367058, 17908.641827163483, 18088.54778630535, 18819.43541693748, 5300.628955973083, 5675.943240567603, 5717.9714101429645, 5725.7995970141055, 5727.599068065237, 5483.369412517552, 5640.971795141039, 5718.857028574298, 5731.0, 5731.226284451587, 5589.692566908819, 5648.378678345399, 5692.316921969369, 5695.174199741035, 5695.971520142414, 5410.486003829669, 5611.158326251054, 5632.802851900185, 5634.04221282383, 5639.0, 5067.214581739836, 5069.214271787879, 5072.112380333427, 5072.568831649314, 5072.6195535335, 21568.676469852966, 21605.891970540204, 22221.777782222132, 22363.55272894549, 22458.0, 2074.4502706782714, 2266.6826644269813, 2348.694669692929, 2777.5139350613763, 2993.6079722928944, 9807.803843923153, 10436.478176091165, 10440.634577789817, 14237.0, 14297.857021429756, 1877.915493802782, 1878.1736036144052, 1891.4133226924653, 1962.8707603525893, 1977.2980591889816, 75979.08355579509, 79340.0, 79975.6001219996, 80822.59588702078, 81190.0, 10886.564537418415, 10923.0, 10960.342379457099, 11054.723631909106, 11354.829677554679, 15690.215489225493, 15709.135997520247, 15714.057156570643, 15735.763963540556, 15740.212989350715, 6795.93204067953, 7148.964255178691, 7538.396928245727, 7597.810054748676, 7761.068671759425, 7482.812929676693, 7521.8871716923195, 7627.771166865003, 7734.6132693366235, 7767.300942915052, 4570.65720070996, 4779.211430114062, 4781.474037855833, 4875.609951203895, 4929.679570827956, 1765.66161986513, 1901.6481950839138, 1949.5905859769323, 2009.3770931011434, 2019.2024150460486, 849.8215374771244, 883.8895138107737, 931.7484279244566, 953.1516949914582, 976.9936964926137, 17499.38752143669, 17686.734698979526, 18504.814951850578, 18827.435176944713, 19008.144633491527, 881.6341218394423, 926.2682480840195, 934.7476181430976, 939.4081728511131, 1004.0561871840592, 9686.564104615309, 10222.488875556193, 10291.279610427198, 10340.482975851177, 10360.844587331196, 450846.0, 451226.0, 469997.0, 470129.0, 478301.0, 93510.53244733869, 95551.52224238834, 97151.0, 97522.0, 99785.50536236507, 981.1611072532882, 1008.5814387029448, 1039.111559616517, 1051.9263651544318, 1052.936823790575, 16096.195190240674, 16866.66266674684, 17220.0, 17636.29454821844, 17823.10884455798, 41450.0, 42106.15787684168, 42934.57065429306, 43108.35337470003, 43140.0, 59149.70425147847, 59621.403785961586, 59739.40260597512, 60841.391586085345, 61100.0, 6341.397567231105, 8146.389020823343, 8182.83634327322, 8460.450070745383, 8810.603522841615, 11386.373749443861, 12852.614421567368, 12854.357282136043, 12864.935675321563, 13250.602481925558, 10755.48269726701, 11266.436678165896, 11464.484098215768, 11579.247348922294, 11638.941805290919, 11213.943930280297, 11275.15436342294, 11332.0, 11346.432678366215, 11395.943020285176, 11016.724581885197, 11126.833097503706, 11563.6530904073, 11595.130365222474, 11681.532738690357, 8541.487510749375, 8612.65549378018, 8616.913830861611, 8764.737057888273, 8976.435862014112, 9497.715068547956, 11534.711632209262, 11678.941605291919, 11813.0, 11852.644420667393], "value": 9036.05907316664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.3759402254043, 1483.9777403338949, 1629.6251862071724, 1746.0833062642118, 1770.9203085861136, 8094.109647938722, 8363.665453381858, 8788.472691638506, 9586.376885502437, 9593.232541396688, 8865.246454051397, 9183.678571250008, 9220.815583688322, 9489.0, 9490.050994900506, 2413.770691784282, 2439.902044080171, 2441.0, 2448.3879030242438, 2461.6061430171176, 2066.111572024029, 2136.8824714640687, 2368.39605900496, 2385.0817435287368, 2423.9394015149587, 2341.637046257833, 2417.5527527407485, 2423.333583264599, 2437.4881274932286, 2441.5727247731666, 2456.5332586808627, 2467.0255249176566, 2467.629855521677, 2484.7266800651914, 2497.7627125423055, 2402.7236867760257, 2409.578323793329, 2415.384077060356, 2420.9757902420924, 2450.6446565248116, 9353.719388418358, 9379.467543763214, 9486.667966621135, 9505.714828555154, 9506.0, 5382.354117505885, 5527.309086364205, 5537.197106419547, 5539.196816461631, 5539.362973258087, 3375.6961873431264, 3485.4074807282823, 3541.9645803541894, 3550.9644903551152, 3557.5197348358156, 1139.7834411461795, 1180.8110702287593, 1214.5384753793503, 1221.6335099470123, 1227.4537830665358, 13246.40391182399, 13253.204807711472, 13294.268815215159, 13297.86702132976, 13306.467741290337, 4852.1508735971065, 4942.604591632661, 5036.6726162799305, 5138.152204886227, 5208.765605547759, 10211.183105351554, 10379.273450858518, 10388.220883433622, 10393.220508461896, 10400.375977441226, 5603.3836278009385, 5603.971980140073, 5606.831795046155, 5609.354924183771, 5616.213730077792, 5029.321041659329, 5120.61595380348, 5147.124988751922, 5219.451957544479, 5231.241469986907, 3888.5528164260904, 3927.351986922183, 3944.447777311179, 4025.798710064485, 4026.476558047435, 1094.2668412163869, 1134.0, 1158.31659321001, 1175.623800383886, 1177.604538621736, 897.9146981036791, 938.5354249646468, 940.8521603643544, 944.8063147054837, 945.8581212818133, 5884.735186916599, 5940.673262970492, 5995.580309378389, 6167.96916015426, 6245.0632405139595, 939.6711151097198, 952.2144231009445, 961.5144352102284, 995.8257304971601, 1002.6039714312806, 4002.3396139636634, 4238.131183107455, 4292.720973136736, 4423.314386270134, 4434.312681534368, 11306.886931130748, 11613.6515904523, 11701.29792212453, 11705.414729263503, 11716.531338746354, 11606.419679016015, 11607.071434285237, 11629.88370116305, 11639.534418623329, 11646.534138634359, 8124.471909325875, 8539.231469167804, 8548.401611887108, 8550.059493455716, 8812.51531165792, 6121.0, 6212.347703491068, 6345.936540634534, 6384.712687929147, 6466.482681385572, 10709.303895246783, 10821.242513024014, 10844.295120817122, 11070.335779853234, 11090.279131856403, 10829.18781091437, 10861.185411094042, 11193.160512961398, 11246.943765281121, 11278.718032049264, 8122.472039317426, 8304.04503482083, 8394.832103357776, 8395.790105247417, 8408.159184081544, 8603.354748393771, 8621.396502244783, 8709.390342675952, 8710.869336959824, 8713.91286087131, 8152.714654987047, 8156.143604921398, 8178.754637360888, 8192.713255036046, 8422.620982055945, 7820.882686759589, 7909.406794490321, 8142.592870356577, 8178.263956243966, 8233.135520770473, 7192.388646965067, 7269.709211631475, 7275.563466192046, 7331.266873312626, 7492.026036615205, 5264.2630031794815, 5279.920801188062, 5294.179402192666, 5305.9469405307, 5407.134858422633, 5200.323957885451, 5279.297816611378, 5309.601779866448, 5414.3232095988005, 5540.088669409048], "value": 4834.480977407589, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.5051299333483, 337.56959876157913, 341.60544571020466, 349.0488419058067, 350.07405412683437, 5281.656692315001, 5300.89398212036, 5329.227262047001, 5348.73256337183, 5414.702191379482, 5291.973540132303, 5327.147656374981, 5345.7594408251625, 5376.843978544612, 5381.165919282517, 4015.7992100394936, 4141.275276826558, 4149.5228048774325, 4182.33082706767, 4207.326827707568, 4168.916081818718, 4181.979090104561, 4202.348635961431, 4235.83056677734, 4239.72808157553, 4133.627973482386, 4143.212789569987, 4156.729812562189, 4172.144710334374, 4180.122174343376, 3915.530136383638, 3923.0, 4159.5216550096675, 4179.561146079648, 4212.178625168093, 3696.8706095286807, 3699.5952630790202, 3705.370087085202, 3708.629137086297, 3752.3808571585655, 5167.534921857014, 5181.041507321158, 5194.402643695986, 5195.454477279907, 5253.816116435944, 4415.735055896656, 4647.512011238805, 4687.765611719435, 4819.542143496362, 4821.614270858358, 5495.670259784426, 5506.449355064502, 5511.58663100269, 5513.393526712059, 5520.199571062173, 3940.389239667856, 3956.3076461619103, 3960.1881614269005, 3965.306071437487, 3977.423273625308, 5450.345958484968, 5680.5455563554815, 5707.68607726579, 5732.914006289909, 5750.79872204475, 4088.100617864065, 4110.17796440713, 4111.835526578963, 4115.670746340285, 4117.835286588563, 4173.269677806372, 4173.269677806372, 4184.811683474251, 4187.602177793105, 4206.8737937861915, 4508.0, 4756.7145971241835, 4769.332293478916, 4774.641901857305, 4786.377770889762, 5567.053600887859, 5586.804461843816, 5592.161175823578, 5594.440555944373, 5594.496495315359, 1419.2052450627682, 1446.9565913022627, 1448.8696017358488, 1451.9346629401703, 1455.505128256398, 866.8699695045667, 921.4747593871429, 928.537553353471, 943.2218419803689, 944.2163004706077, 5122.564582010496, 5262.342207224097, 5275.340582427197, 5442.156465747816, 5453.4273901240595, 5736.627119237236, 5740.397258287903, 5741.368449470555, 5761.366249712528, 5764.769409223668, 5210.374755029421, 5407.324084489439, 5423.620346575781, 5432.728363581805, 5442.482964118402, 3012.3372857971212, 3044.588980487606, 3051.4965030770118, 3052.9925124708793, 3076.138681169276, 5876.294844618673, 5882.558808089325, 5886.293644762571, 5930.9406905931255, 5938.683913273827, 5629.4652008059165, 5792.536597072224, 5812.210139133155, 5827.213326200994, 5831.650100993869, 5860.677662728591, 5863.912041319383, 5873.119032145127, 5874.735636896265, 5909.970450147808, 5343.9465605345, 5605.094267948917, 5617.634853734494, 5666.405027472056, 5700.486956173964, 5592.412796656293, 5605.5515558756015, 5605.831825045255, 5606.747696353756, 5617.382087970402, 5829.300483941955, 5831.650100993954, 5842.211301474246, 5845.152452894392, 5852.800175964002, 3961.3956347679236, 4068.552459229423, 4074.307367747431, 4081.326581114202, 4093.6725061995567, 5620.213170156099, 5636.9436305638055, 5644.4195625225, 5649.435056494318, 5655.406182350958, 5509.201165830852, 5515.310586176728, 5560.860978475571, 5561.4716601922755, 5575.9163612545035, 5321.36143662763, 5493.972530137484, 5509.917351239815, 5543.861403464945, 5561.833145005657, 5433.565314774887, 5474.206240095244, 5480.506754392124, 5493.340799104133, 5498.835034948958, 5335.678979643867, 5373.623846330719, 5478.616496845182, 5548.528375088163, 5592.832215033555, 5471.370792358771, 5480.451954804487, 5485.8182254293215, 5490.258815059916, 5491.533219676372], "value": 4247.03793579683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.947653925955, 724.3589423360326, 725.89663711159, 754.7999780058284, 781.1446466119601, 14754.852451475483, 15532.0, 15739.921300393511, 15752.448664296766, 15754.369825206992, 13285.40215690294, 13320.866791332084, 13346.132501387403, 13356.13185142965, 13508.392122354493, 650.9088727578143, 666.7799626123392, 788.0779487999026, 790.9446338756289, 801.8276070644811, 791.9049714034323, 797.7168105322614, 800.4836880212259, 802.0455657767268, 803.7990502374406, 841.0664162779341, 843.3421930893882, 848.2154007543029, 848.9533075680835, 849.023622833743, 669.5714742564787, 683.5761827666829, 829.8257365953157, 848.2365870716343, 848.3086284677998, 611.4405319132987, 617.2747022248884, 617.2808677890253, 617.478230894893, 617.57387402692, 14885.627859303495, 14931.626709332353, 14949.850501494955, 14963.326650300654, 14965.102093874402, 13678.452861885613, 13858.376373063136, 14058.648533786634, 14149.434022639081, 14530.636734081627, 12831.0, 12978.675533111653, 13201.801972970317, 13478.258695771729, 13738.931305343509, 4311.245532031882, 4394.0, 4401.295792673157, 4413.815161495048, 4582.541745825424, 73941.0, 75338.62330688366, 76699.0, 76937.23062769357, 77027.0, 10989.780204395867, 11178.776424471465, 11217.775644487065, 11391.886081139082, 11946.462409191607, 21422.89288553547, 21643.567128657654, 21687.45781355479, 21725.348239552837, 21730.0, 7670.463067585216, 7797.337226335711, 7835.138134805167, 7910.7231246906085, 8002.199780022068, 4212.410262563244, 4224.176285624335, 4362.105768317488, 4394.340848872631, 4531.864044078683, 3371.8145501997624, 3483.111806489329, 3484.233468636896, 3485.19840436701, 3486.738494612914, 571.8170185540587, 580.5239703443136, 593.2021431175062, 595.7884950842475, 600.2946537818046, 506.5263978180413, 519.966202196856, 579.2990481517411, 585.819603186382, 640.7565125252468, 9391.295652826066, 9576.616935322649, 9594.856077158849, 10316.742081448025, 10702.555155054124, 680.251723104581, 722.6856317501904, 722.8337482379083, 738.6159197217524, 764.3808515102673, 4612.377329060534, 4887.511248875084, 4901.460839307673, 4922.286268491049, 4945.653804233669, 100582.0, 114988.0, 120965.0, 121675.0, 121884.0, 96393.0, 99459.50270248747, 100344.0, 101508.0, 106352.0, 83738.0, 86012.56993715116, 86020.56989715011, 86565.0, 90949.09050909539, 8897.743686805747, 9148.542572871464, 9177.954110229406, 9201.953990230006, 9328.347015709107, 36883.63116368802, 48920.26619600638, 49712.50287497223, 50302.49697502978, 50590.24114638357, 68746.65626671835, 76943.61528192324, 77241.61379193068, 78014.0, 79468.60265698635, 11857.0, 11990.820137697767, 12056.879431205576, 12102.818457722966, 12820.423080961193, 13369.0, 13397.53108641193, 13586.524471643444, 13684.794728078889, 14520.709585808436, 11905.702357441134, 12447.190932589352, 12498.008126842642, 13134.211947283193, 13482.730345393233, 12550.43523041447, 12593.370331483206, 12666.0, 12915.741685166431, 12934.741305174031, 12407.190406143187, 12707.237565746085, 12869.420876060412, 13156.60530184096, 13423.194608323533, 10120.443375614117, 10293.0, 10476.476176191007, 10723.463826808473, 10918.836217456903, 9200.861987070066, 9820.165285950774, 10210.438425886647, 10336.948315258676, 10377.84433233487], "value": 7333.603855179122, "unit": "score"}], "name": "dromaeo_css", "value": 7177.892504377052, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 111.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 119.0, 156.0, 120.0, 119.0, 117.0, 124.0, 148.0, 120.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 163.0, 161.0, 163.0, 162.0, 161.0, 162.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 115.0, 74.0, 77.0, 77.0, 76.0, 100.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 119.0, 83.0, 82.0, 83.0, 103.0, 83.0, 82.0, 108.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 48.0, 49.0, 51.0, 51.0, 50.0, 51.0, 57.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 59.0, 60.0, 60.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 82.0, 80.0, 78.0, 81.0, 79.0, 86.0, 84.0, 78.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 125.0, 124.0, 135.0, 131.0, 123.0, 137.0, 124.0, 160.0, 136.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 164.0, 169.0, 170.0, 171.0, 164.0, 170.0, 169.0, 164.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 71.0, 66.0, 66.0, 65.0, 66.0, 64.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1492.6000000000001, "alertThreshold": 2.0}]} 19:34:35 INFO - Return code: 0 19:34:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:34:35 INFO - # TBPL SUCCESS # 19:34:35 INFO - Running post-action listener: _resource_record_post_action 19:34:35 INFO - [mozharness: 2016-09-17 02:34:35.886017Z] Finished run-tests step (success) 19:34:35 INFO - Running post-run listener: _resource_record_post_run 19:34:35 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 404545536; Read time: 0; Write time: 266596 19:34:35 INFO - TinderboxPrint: CPU usage
13.1% 19:34:35 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:34:35 INFO - TinderboxPrint: I/O write bytes / time
404,545,536 / 266,596 19:34:35 INFO - TinderboxPrint: CPU idle
8,301.7 (86.9%) 19:34:35 INFO - TinderboxPrint: CPU user
1,192.7 (12.5%) 19:34:35 INFO - TinderboxPrint: Swap in / out
0 / 0 19:34:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114266112; Read time: 0; Write time: 56620 19:34:35 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288989184; Read time: 0; Write time: 209948 19:34:36 INFO - Running post-run listener: _upload_blobber_files 19:34:36 INFO - Blob upload gear active. 19:34:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:34:36 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 19:34:36 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'] 19:34:36 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 19:34:36 INFO - (blobuploader) - INFO - Open directory for files ... 19:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:34:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:37 INFO - (blobuploader) - INFO - Done attempting. 19:34:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:34:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:42 INFO - (blobuploader) - INFO - Done attempting. 19:34:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:34:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:42 INFO - (blobuploader) - INFO - Done attempting. 19:34:42 INFO - (blobuploader) - INFO - Iteration through files over. 19:34:42 INFO - Return code: 0 19:34:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:34:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:34:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b31f6b40760cb4d91ac9957097f1500c052272952480a96318145797e664a2ecc4e74ea18169afff1fb736ec1d6864cf79cac296274b6d761372da9a46e454e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8611452140ce0b384b69450f0c5edbbe90b3ff638e7c35c243f83edfafb9306bbd6eb8fb876b058ae08b41a939d864d5e7d3bec1385045cc267a6e703f52e41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248e0fd528e4dcf49cb36e24ecb8653da6ac71fc047b1dd85ca1be6632496bece491d8e64704ab1fed3b38af47efe13079767e69baff1dd8f1095909a056104d"} 19:34:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:34:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:34:42 INFO - Contents: 19:34:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b31f6b40760cb4d91ac9957097f1500c052272952480a96318145797e664a2ecc4e74ea18169afff1fb736ec1d6864cf79cac296274b6d761372da9a46e454e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8611452140ce0b384b69450f0c5edbbe90b3ff638e7c35c243f83edfafb9306bbd6eb8fb876b058ae08b41a939d864d5e7d3bec1385045cc267a6e703f52e41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248e0fd528e4dcf49cb36e24ecb8653da6ac71fc047b1dd85ca1be6632496bece491d8e64704ab1fed3b38af47efe13079767e69baff1dd8f1095909a056104d"} 19:34:42 INFO - Running post-run listener: copy_logs_to_upload_dir 19:34:42 INFO - Copying logs to upload dir... 19:34:42 INFO - mkdir: /builds/slave/test/build/upload/logs 19:34:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.660850 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-16 19:34:40.292969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 19:34:40.297688) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 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/9b31f6b40760cb4d91ac9957097f1500c052272952480a96318145797e664a2ecc4e74ea18169afff1fb736ec1d6864cf79cac296274b6d761372da9a46e454e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8611452140ce0b384b69450f0c5edbbe90b3ff638e7c35c243f83edfafb9306bbd6eb8fb876b058ae08b41a939d864d5e7d3bec1385045cc267a6e703f52e41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248e0fd528e4dcf49cb36e24ecb8653da6ac71fc047b1dd85ca1be6632496bece491d8e64704ab1fed3b38af47efe13079767e69baff1dd8f1095909a056104d"} build_url:https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011287 build_url: 'https://queue.taskcluster.net/v1/task/X1vJRxDnQBeHg2BgauKsDw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b31f6b40760cb4d91ac9957097f1500c052272952480a96318145797e664a2ecc4e74ea18169afff1fb736ec1d6864cf79cac296274b6d761372da9a46e454e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8611452140ce0b384b69450f0c5edbbe90b3ff638e7c35c243f83edfafb9306bbd6eb8fb876b058ae08b41a939d864d5e7d3bec1385045cc267a6e703f52e41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248e0fd528e4dcf49cb36e24ecb8653da6ac71fc047b1dd85ca1be6632496bece491d8e64704ab1fed3b38af47efe13079767e69baff1dd8f1095909a056104d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 19:34:40.330934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:34:40.331508) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474076502.911432-692154460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004422 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 19:34:40.413925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 19:34:40.414302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 19:34:40.414701) ========= ========= Total master_lag: 0.17 =========