builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1474123795.58 results: success (0) buildid: 20160917030427 builduid: 22161ebd8de14000bff7b48c4be4841a revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.577114) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.577633) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.577972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.592909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.593270) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 07:49:55-- 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% 67.3M=0s 2016-09-17 07:49:56 (67.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144907 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.752312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.752723) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017611 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:55.802621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:49:55.803062) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 07:49:56,226 truncating revision to first 12 chars 2016-09-17 07:49:56,226 Setting DEBUG logging. 2016-09-17 07:49:56,226 attempt 1/10 2016-09-17 07:49:56,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:49:57,166 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.350184 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:49:57.167223) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:57.167590) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:57.168059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:57.168363) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:49:57.178077) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 07:49:57.178393) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:49:57 INFO - MultiFileLogger online at 20160917 07:49:57 in /builds/slave/test 07:49:57 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 07:49:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:57 INFO - {'append_to_log': False, 07:49:57 INFO - 'base_work_dir': '/builds/slave/test', 07:49:57 INFO - 'blob_upload_branch': 'Firefox', 07:49:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:57 INFO - 'branch': 'Firefox', 07:49:57 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:57 INFO - 'config_files': ('talos/linux_config.py',), 07:49:57 INFO - 'default_actions': ('clobber', 07:49:57 INFO - 'read-buildbot-config', 07:49:57 INFO - 'download-and-extract', 07:49:57 INFO - 'populate-webroot', 07:49:57 INFO - 'create-virtualenv', 07:49:57 INFO - 'install', 07:49:57 INFO - 'run-tests'), 07:49:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:57 INFO - 'download_minidump_stackwalk': True, 07:49:57 INFO - 'download_symbols': 'ondemand', 07:49:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:57 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:57 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:57 INFO - 'installer_path': 'installer.exe', 07:49:57 INFO - 'log_level': 'info', 07:49:57 INFO - 'log_name': 'talos', 07:49:57 INFO - 'log_to_console': True, 07:49:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:49:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:49:57 INFO - 'opt_config_files': (), 07:49:57 INFO - 'pip_index': False, 07:49:57 INFO - 'sps_profile': False, 07:49:57 INFO - 'sps_profile_interval': 0, 07:49:57 INFO - 'suite': 'dromaeojs', 07:49:57 INFO - 'system_bits': '32', 07:49:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:57 INFO - 'title': 'talos-linux64-ix-022', 07:49:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:57 INFO - 'use_talos_json': True, 07:49:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:57 INFO - 'work_dir': 'build'} 07:49:57 INFO - [mozharness: 2016-09-17 14:49:57.702602Z] Running clobber step. 07:49:57 INFO - Running pre-action listener: _resource_record_pre_action 07:49:57 INFO - Running main action method: clobber 07:49:57 INFO - rmtree: /builds/slave/test/build 07:49:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:58 INFO - Running post-action listener: _resource_record_post_action 07:49:58 INFO - [mozharness: 2016-09-17 14:49:58.164713Z] Finished clobber step (success) 07:49:58 INFO - [mozharness: 2016-09-17 14:49:58.164814Z] Running read-buildbot-config step. 07:49:58 INFO - Running pre-action listener: _resource_record_pre_action 07:49:58 INFO - Running main action method: read_buildbot_config 07:49:58 INFO - Using buildbot properties: 07:49:58 INFO - { 07:49:58 INFO - "project": "", 07:49:58 INFO - "product": "firefox", 07:49:58 INFO - "script_repo_revision": "production", 07:49:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:49:58 INFO - "repository": "", 07:49:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:49:58 INFO - "buildid": "20160917030427", 07:49:58 INFO - "stage_platform": "linux64-pgo", 07:49:58 INFO - "basedir": "/builds/slave/test", 07:49:58 INFO - "nightly_build": "True", 07:49:58 INFO - "buildnumber": 574, 07:49:58 INFO - "slavename": "talos-linux64-ix-022", 07:49:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:49:58 INFO - "platform": "ubuntu64_hw", 07:49:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:49:58 INFO - "branch": "mozilla-central", 07:49:58 INFO - "repo_path": "mozilla-central", 07:49:58 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 07:49:58 INFO - "pgo_build": "True", 07:49:58 INFO - "builduid": "22161ebd8de14000bff7b48c4be4841a", 07:49:58 INFO - "slavebuilddir": "test" 07:49:58 INFO - } 07:49:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:49:58 INFO - Running post-action listener: _resource_record_post_action 07:49:58 INFO - [mozharness: 2016-09-17 14:49:58.167356Z] Finished read-buildbot-config step (success) 07:49:58 INFO - [mozharness: 2016-09-17 14:49:58.167446Z] Running download-and-extract step. 07:49:58 INFO - Running pre-action listener: _resource_record_pre_action 07:49:58 INFO - Running main action method: download_and_extract 07:49:58 INFO - mkdir: /builds/slave/test/build/tests 07:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:58 INFO - trying https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:49:58 INFO - Downloading https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/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 07:49:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/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 07:49:58 INFO - Downloaded 1361 bytes. 07:49:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:49:58 INFO - Using the following test package requirements: 07:49:58 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:49:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:49:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'jsshell-linux-x86_64.zip'], 07:49:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:49:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:49:58 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:49:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:49:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:49:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:49:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:49:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:58 INFO - Fetch https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:49:59 INFO - Expected file size: 21936355 07:50:03 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 07:50:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:50:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:50:03 INFO - Fetch https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:50:04 INFO - Expected file size: 21936355 07:50:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:50:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:50:08 INFO - Fetch https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:50:09 INFO - Expected file size: 14414048 07:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:09 INFO - trying https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:50:09 INFO - Downloading https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/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 07:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/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 07:50:11 INFO - Downloaded 59555051 bytes. 07:50:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:50:11 INFO - mkdir: /builds/slave/test/properties 07:50:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:50:11 INFO - Writing to file /builds/slave/test/properties/build_url 07:50:11 INFO - Contents: 07:50:11 INFO - build_url:https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:50:11 INFO - Running post-action listener: _resource_record_post_action 07:50:11 INFO - Running post-action listener: set_extra_try_arguments 07:50:11 INFO - [mozharness: 2016-09-17 14:50:11.654412Z] Finished download-and-extract step (success) 07:50:11 INFO - [mozharness: 2016-09-17 14:50:11.654640Z] Running populate-webroot step. 07:50:11 INFO - Running pre-action listener: _resource_record_pre_action 07:50:11 INFO - Running main action method: populate_webroot 07:50:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:50:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:50:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:50:11 INFO - '--tppagecycles', 07:50:11 INFO - '7'], 07:50:11 INFO - 'tests': ['tsvgm']}}, 07:50:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:50:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:50:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:50:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:50:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['damp', 'tps']}, 07:50:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'tests': ['damp', 'tps']}, 07:50:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['dromaeo_dom']}, 07:50:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:50:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['basic_compositor_video']}, 07:50:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:50:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'other-e10s': {'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['a11yr', 07:50:11 INFO - 'ts_paint', 07:50:11 INFO - 'tpaint', 07:50:11 INFO - 'sessionrestore', 07:50:11 INFO - 'sessionrestore_no_auto_restore', 07:50:11 INFO - 'tabpaint']}, 07:50:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['tsvgx', 07:50:11 INFO - 'tsvgr_opacity', 07:50:11 INFO - 'tart', 07:50:11 INFO - 'tscrollx', 07:50:11 INFO - 'cart']}, 07:50:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:50:11 INFO - 'tsvgr_opacity', 07:50:11 INFO - 'tart', 07:50:11 INFO - 'tscrollx', 07:50:11 INFO - 'cart']}, 07:50:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'talos_options': ['--disable-e10s'], 07:50:11 INFO - 'tests': ['tp5o']}, 07:50:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'tests': ['tp5o']}, 07:50:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'talos_options': ['--disable-e10s', 07:50:11 INFO - '--xperf_path', 07:50:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:11 INFO - 'tests': ['tp5n']}, 07:50:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:11 INFO - 'talos_options': ['--xperf_path', 07:50:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:11 INFO - 'tests': ['tp5n']}}, 07:50:11 INFO - 'talos.zip': {'path': '', 07:50:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:50:11 INFO - Running post-action listener: _resource_record_post_action 07:50:11 INFO - [mozharness: 2016-09-17 14:50:11.677288Z] Finished populate-webroot step (success) 07:50:11 INFO - [mozharness: 2016-09-17 14:50:11.677388Z] Running create-virtualenv step. 07:50:11 INFO - Running pre-action listener: _resource_record_pre_action 07:50:11 INFO - Running main action method: create_virtualenv 07:50:11 INFO - Creating virtualenv /builds/slave/test/build/venv 07:50:11 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 07:50:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:50:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:50:11 INFO - Using real prefix '/usr' 07:50:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:50:12 INFO - Installing distribute.............................................................................................................................................................................................done. 07:50:14 INFO - Installing pip.................done. 07:50:14 INFO - Return code: 0 07:50:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:14 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:14 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 07:50:14 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 07:50:14 INFO - Using env: {'DISPLAY': ':0', 07:50:14 INFO - 'HOME': '/home/cltbld', 07:50:14 INFO - 'LANG': 'en_US.UTF-8', 07:50:14 INFO - 'LANGUAGE': 'en_US:en', 07:50:14 INFO - 'LOGNAME': 'cltbld', 07:50:14 INFO - 'MAIL': '/var/mail/cltbld', 07:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:14 INFO - 'MOZ_NO_REMOTE': '1', 07:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:14 INFO - 'NO_EM_RESTART': '1', 07:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:14 INFO - 'PWD': '/builds/slave/test', 07:50:14 INFO - 'SHELL': '/bin/bash', 07:50:14 INFO - 'SHLVL': '1', 07:50:14 INFO - 'TERM': 'linux', 07:50:14 INFO - 'TMOUT': '86400', 07:50:14 INFO - 'USER': 'cltbld', 07:50:14 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:14 INFO - '_': '/tools/buildbot/bin/python'} 07:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:14 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 07:50:14 INFO - Cleaning up... 07:50:14 INFO - Return code: 0 07:50:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:14 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:14 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 07:50:14 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 07:50:14 INFO - Using env: {'DISPLAY': ':0', 07:50:14 INFO - 'HOME': '/home/cltbld', 07:50:14 INFO - 'LANG': 'en_US.UTF-8', 07:50:14 INFO - 'LANGUAGE': 'en_US:en', 07:50:14 INFO - 'LOGNAME': 'cltbld', 07:50:14 INFO - 'MAIL': '/var/mail/cltbld', 07:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:14 INFO - 'MOZ_NO_REMOTE': '1', 07:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:14 INFO - 'NO_EM_RESTART': '1', 07:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:14 INFO - 'PWD': '/builds/slave/test', 07:50:14 INFO - 'SHELL': '/bin/bash', 07:50:14 INFO - 'SHLVL': '1', 07:50:14 INFO - 'TERM': 'linux', 07:50:14 INFO - 'TMOUT': '86400', 07:50:14 INFO - 'USER': 'cltbld', 07:50:14 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:14 INFO - '_': '/tools/buildbot/bin/python'} 07:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:14 INFO - Downloading/unpacking psutil>=3.1.1 07:50:14 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 07:50:14 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 07:50:14 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 07:50:14 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 07:50:14 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 07:50:14 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 07:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:50:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:16 INFO - Installing collected packages: psutil 07:50:16 INFO - Running setup.py install for psutil 07:50:16 INFO - building 'psutil._psutil_linux' extension 07:50:16 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 07:50:16 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 07:50:16 INFO - building 'psutil._psutil_posix' extension 07:50:16 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 07:50:17 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 07:50:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:17 INFO - Successfully installed psutil 07:50:17 INFO - Cleaning up... 07:50:17 INFO - Return code: 0 07:50:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:50:17 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')]} 07:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:17 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')]} 07:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:17 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:17 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 07:50:17 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 07:50:17 INFO - Using env: {'DISPLAY': ':0', 07:50:17 INFO - 'HOME': '/home/cltbld', 07:50:17 INFO - 'LANG': 'en_US.UTF-8', 07:50:17 INFO - 'LANGUAGE': 'en_US:en', 07:50:17 INFO - 'LOGNAME': 'cltbld', 07:50:17 INFO - 'MAIL': '/var/mail/cltbld', 07:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:17 INFO - 'MOZ_NO_REMOTE': '1', 07:50:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:17 INFO - 'NO_EM_RESTART': '1', 07:50:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:17 INFO - 'PWD': '/builds/slave/test', 07:50:17 INFO - 'SHELL': '/bin/bash', 07:50:17 INFO - 'SHLVL': '1', 07:50:17 INFO - 'TERM': 'linux', 07:50:17 INFO - 'TMOUT': '86400', 07:50:17 INFO - 'USER': 'cltbld', 07:50:17 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:17 INFO - '_': '/tools/buildbot/bin/python'} 07:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:50:19 INFO - Installing collected packages: mozsystemmonitor 07:50:19 INFO - Running setup.py install for mozsystemmonitor 07:50:19 INFO - Successfully installed mozsystemmonitor 07:50:19 INFO - Cleaning up... 07:50:19 INFO - Return code: 0 07:50:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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', '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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:50: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 jsonschema==2.5.1 07:50:19 INFO - Using env: {'DISPLAY': ':0', 07:50:19 INFO - 'HOME': '/home/cltbld', 07:50:19 INFO - 'LANG': 'en_US.UTF-8', 07:50:19 INFO - 'LANGUAGE': 'en_US:en', 07:50:19 INFO - 'LOGNAME': 'cltbld', 07:50:19 INFO - 'MAIL': '/var/mail/cltbld', 07:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:19 INFO - 'MOZ_NO_REMOTE': '1', 07:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:19 INFO - 'NO_EM_RESTART': '1', 07:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:19 INFO - 'PWD': '/builds/slave/test', 07:50:19 INFO - 'SHELL': '/bin/bash', 07:50:19 INFO - 'SHLVL': '1', 07:50:19 INFO - 'TERM': 'linux', 07:50:19 INFO - 'TMOUT': '86400', 07:50:19 INFO - 'USER': 'cltbld', 07:50:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:19 INFO - '_': '/tools/buildbot/bin/python'} 07:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:19 INFO - Downloading/unpacking jsonschema==2.5.1 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:50:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:50:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:50:21 INFO - warning: no files found matching '*.txt' 07:50:21 INFO - no previously-included directories found matching 'build' 07:50:21 INFO - no previously-included directories found matching 'dist' 07:50:21 INFO - no previously-included directories found matching '.git*' 07:50:21 INFO - Installing collected packages: jsonschema, functools32 07:50:21 INFO - Running setup.py install for functools32 07:50:22 INFO - warning: no files found matching '*.txt' 07:50:22 INFO - no previously-included directories found matching 'build' 07:50:22 INFO - no previously-included directories found matching 'dist' 07:50:22 INFO - no previously-included directories found matching '.git*' 07:50:22 INFO - Successfully installed jsonschema functools32 07:50:22 INFO - Cleaning up... 07:50:22 INFO - Return code: 0 07:50:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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', '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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:50: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 functools32==3.2.3-2 07:50:22 INFO - Using env: {'DISPLAY': ':0', 07:50:22 INFO - 'HOME': '/home/cltbld', 07:50:22 INFO - 'LANG': 'en_US.UTF-8', 07:50:22 INFO - 'LANGUAGE': 'en_US:en', 07:50:22 INFO - 'LOGNAME': 'cltbld', 07:50:22 INFO - 'MAIL': '/var/mail/cltbld', 07:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:22 INFO - 'MOZ_NO_REMOTE': '1', 07:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:22 INFO - 'NO_EM_RESTART': '1', 07:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:22 INFO - 'PWD': '/builds/slave/test', 07:50:22 INFO - 'SHELL': '/bin/bash', 07:50:22 INFO - 'SHLVL': '1', 07:50:22 INFO - 'TERM': 'linux', 07:50:22 INFO - 'TMOUT': '86400', 07:50:22 INFO - 'USER': 'cltbld', 07:50:22 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:22 INFO - '_': '/tools/buildbot/bin/python'} 07:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:50:22 INFO - Cleaning up... 07:50:22 INFO - Return code: 0 07:50:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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', '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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:50: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 blobuploader==1.2.4 07:50:22 INFO - Using env: {'DISPLAY': ':0', 07:50:22 INFO - 'HOME': '/home/cltbld', 07:50:22 INFO - 'LANG': 'en_US.UTF-8', 07:50:22 INFO - 'LANGUAGE': 'en_US:en', 07:50:22 INFO - 'LOGNAME': 'cltbld', 07:50:22 INFO - 'MAIL': '/var/mail/cltbld', 07:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:22 INFO - 'MOZ_NO_REMOTE': '1', 07:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:22 INFO - 'NO_EM_RESTART': '1', 07:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:22 INFO - 'PWD': '/builds/slave/test', 07:50:22 INFO - 'SHELL': '/bin/bash', 07:50:22 INFO - 'SHLVL': '1', 07:50:22 INFO - 'TERM': 'linux', 07:50:22 INFO - 'TMOUT': '86400', 07:50:22 INFO - 'USER': 'cltbld', 07:50:22 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:22 INFO - '_': '/tools/buildbot/bin/python'} 07:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:22 INFO - Downloading/unpacking blobuploader==1.2.4 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:24 INFO - Downloading blobuploader-1.2.4.tar.gz 07:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:50:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:50:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:25 INFO - Downloading docopt-0.6.1.tar.gz 07:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:50:25 INFO - Installing collected packages: blobuploader, requests, docopt 07:50:25 INFO - Running setup.py install for blobuploader 07:50:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:50:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:50:25 INFO - Running setup.py install for requests 07:50:25 INFO - Running setup.py install for docopt 07:50:25 INFO - Successfully installed blobuploader requests docopt 07:50:25 INFO - Cleaning up... 07:50:25 INFO - Return code: 0 07:50:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:25 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')]} 07:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:25 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')]} 07:50:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:25 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:25 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 07:50:25 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 07:50:25 INFO - Using env: {'DISPLAY': ':0', 07:50:25 INFO - 'HOME': '/home/cltbld', 07:50:25 INFO - 'LANG': 'en_US.UTF-8', 07:50:25 INFO - 'LANGUAGE': 'en_US:en', 07:50:25 INFO - 'LOGNAME': 'cltbld', 07:50:25 INFO - 'MAIL': '/var/mail/cltbld', 07:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:25 INFO - 'MOZ_NO_REMOTE': '1', 07:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:25 INFO - 'NO_EM_RESTART': '1', 07:50:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:25 INFO - 'PWD': '/builds/slave/test', 07:50:25 INFO - 'SHELL': '/bin/bash', 07:50:25 INFO - 'SHLVL': '1', 07:50:25 INFO - 'TERM': 'linux', 07:50:25 INFO - 'TMOUT': '86400', 07:50:25 INFO - 'USER': 'cltbld', 07:50:25 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:25 INFO - '_': '/tools/buildbot/bin/python'} 07:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:26 INFO - Running setup.py (path:/tmp/pip-vOJWoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:26 INFO - Running setup.py (path:/tmp/pip-x_cFHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:26 INFO - Running setup.py (path:/tmp/pip-WI1lGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:26 INFO - Running setup.py (path:/tmp/pip-3c0v_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:26 INFO - Running setup.py (path:/tmp/pip-Hotta5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:26 INFO - Running setup.py (path:/tmp/pip-_gyzMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:26 INFO - Running setup.py (path:/tmp/pip-BUociE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:26 INFO - Running setup.py (path:/tmp/pip-FRHpR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:26 INFO - Running setup.py (path:/tmp/pip-RvQlz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:26 INFO - Running setup.py (path:/tmp/pip-5o3iXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:26 INFO - Running setup.py (path:/tmp/pip-kNzKkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:26 INFO - Running setup.py (path:/tmp/pip-D4UkFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:26 INFO - Running setup.py (path:/tmp/pip-xSAoIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:26 INFO - Running setup.py (path:/tmp/pip-Uxpvwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:27 INFO - Running setup.py (path:/tmp/pip-cQr_CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:27 INFO - Running setup.py (path:/tmp/pip-kOBygB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:27 INFO - Running setup.py (path:/tmp/pip-Yp8R3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:50:27 INFO - Running setup.py install for manifestparser 07:50:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:50:27 INFO - Running setup.py install for mozcrash 07:50:27 INFO - Running setup.py install for mozdebug 07:50:27 INFO - Running setup.py install for mozdevice 07:50:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:50:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:50:27 INFO - Running setup.py install for mozfile 07:50:27 INFO - Running setup.py install for mozhttpd 07:50:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:50:27 INFO - Running setup.py install for mozinfo 07:50:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:50:27 INFO - Running setup.py install for mozInstall 07:50:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Running setup.py install for mozleak 07:50:28 INFO - Running setup.py install for mozlog 07:50:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Running setup.py install for moznetwork 07:50:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Running setup.py install for mozprocess 07:50:28 INFO - Running setup.py install for mozprofile 07:50:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Running setup.py install for mozrunner 07:50:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:50:28 INFO - Running setup.py install for mozscreenshot 07:50:28 INFO - Running setup.py install for moztest 07:50:28 INFO - Running setup.py install for mozversion 07:50:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:50:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:50:29 INFO - Cleaning up... 07:50:29 INFO - Return code: 0 07:50:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:29 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')]} 07:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:29 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')]} 07:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:29 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:29 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 07:50:29 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 07:50:29 INFO - Using env: {'DISPLAY': ':0', 07:50:29 INFO - 'HOME': '/home/cltbld', 07:50:29 INFO - 'LANG': 'en_US.UTF-8', 07:50:29 INFO - 'LANGUAGE': 'en_US:en', 07:50:29 INFO - 'LOGNAME': 'cltbld', 07:50:29 INFO - 'MAIL': '/var/mail/cltbld', 07:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:29 INFO - 'MOZ_NO_REMOTE': '1', 07:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:29 INFO - 'NO_EM_RESTART': '1', 07:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:29 INFO - 'PWD': '/builds/slave/test', 07:50:29 INFO - 'SHELL': '/bin/bash', 07:50:29 INFO - 'SHLVL': '1', 07:50:29 INFO - 'TERM': 'linux', 07:50:29 INFO - 'TMOUT': '86400', 07:50:29 INFO - 'USER': 'cltbld', 07:50:29 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:29 INFO - '_': '/tools/buildbot/bin/python'} 07:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:29 INFO - Running setup.py (path:/tmp/pip-yGLgYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:29 INFO - Running setup.py (path:/tmp/pip-1VK0JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:29 INFO - Running setup.py (path:/tmp/pip-TjxVM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:29 INFO - Running setup.py (path:/tmp/pip-3c0REk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:29 INFO - Running setup.py (path:/tmp/pip-xim7c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:29 INFO - Running setup.py (path:/tmp/pip-vzKmiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:29 INFO - Running setup.py (path:/tmp/pip-lSALO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:29 INFO - Running setup.py (path:/tmp/pip-OGCm1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:29 INFO - Running setup.py (path:/tmp/pip-r8p9Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:29 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)) 07:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:29 INFO - Running setup.py (path:/tmp/pip-6Jpr5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:30 INFO - Running setup.py (path:/tmp/pip-0uGS0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:30 INFO - Running setup.py (path:/tmp/pip-FwAfF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:30 INFO - Running setup.py (path:/tmp/pip-O1VOu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:30 INFO - Running setup.py (path:/tmp/pip-NxY9kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:30 INFO - Running setup.py (path:/tmp/pip-tL455f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:30 INFO - Running setup.py (path:/tmp/pip-LWvmlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:30 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)) 07:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:30 INFO - Running setup.py (path:/tmp/pip-5WCUkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:30 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)) 07:50:30 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)) 07:50:30 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)) 07:50:30 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)) 07:50:30 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)) 07:50:30 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)) 07:50:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:50:30 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 07:50:30 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 07:50:30 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 07:50:30 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 07:50:30 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 07:50:30 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 07:50:32 INFO - Downloading blessings-1.6.tar.gz 07:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:50:32 INFO - Installing collected packages: blessings 07:50:32 INFO - Running setup.py install for blessings 07:50:32 INFO - Successfully installed blessings 07:50:32 INFO - Cleaning up... 07:50:32 INFO - Return code: 0 07:50:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:50:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:32 INFO - Reading from file tmpfile_stdout 07:50:32 INFO - Current package versions: 07:50:32 INFO - argparse == 1.2.1 07:50:32 INFO - blessings == 1.6 07:50:32 INFO - blobuploader == 1.2.4 07:50:32 INFO - docopt == 0.6.1 07:50:32 INFO - functools32 == 3.2.3-2 07:50:32 INFO - jsonschema == 2.5.1 07:50:32 INFO - manifestparser == 1.1 07:50:32 INFO - mozInstall == 1.12 07:50:32 INFO - mozcrash == 0.17 07:50:32 INFO - mozdebug == 0.1 07:50:32 INFO - mozdevice == 0.48 07:50:32 INFO - mozfile == 1.2 07:50:32 INFO - mozhttpd == 0.7 07:50:32 INFO - mozinfo == 0.9 07:50:32 INFO - mozleak == 0.1 07:50:32 INFO - mozlog == 3.4 07:50:32 INFO - moznetwork == 0.27 07:50:32 INFO - mozprocess == 0.23 07:50:32 INFO - mozprofile == 0.28 07:50:32 INFO - mozrunner == 6.12 07:50:32 INFO - mozscreenshot == 0.1 07:50:32 INFO - mozsystemmonitor == 0.3 07:50:32 INFO - moztest == 0.7 07:50:32 INFO - mozversion == 1.4 07:50:32 INFO - psutil == 3.1.1 07:50:32 INFO - requests == 1.2.3 07:50:32 INFO - wsgiref == 0.1.2 07:50:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:32 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:32 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 07:50:32 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 07:50:32 INFO - Using env: {'DISPLAY': ':0', 07:50:32 INFO - 'HOME': '/home/cltbld', 07:50:32 INFO - 'LANG': 'en_US.UTF-8', 07:50:32 INFO - 'LANGUAGE': 'en_US:en', 07:50:32 INFO - 'LOGNAME': 'cltbld', 07:50:32 INFO - 'MAIL': '/var/mail/cltbld', 07:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:32 INFO - 'MOZ_NO_REMOTE': '1', 07:50:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:32 INFO - 'NO_EM_RESTART': '1', 07:50:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:32 INFO - 'PWD': '/builds/slave/test', 07:50:32 INFO - 'SHELL': '/bin/bash', 07:50:32 INFO - 'SHLVL': '1', 07:50:32 INFO - 'TERM': 'linux', 07:50:32 INFO - 'TMOUT': '86400', 07:50:32 INFO - 'USER': 'cltbld', 07:50:32 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:32 INFO - '_': '/tools/buildbot/bin/python'} 07:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 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)) 07:50:33 INFO - Cleaning up... 07:50:33 INFO - Return code: 0 07:50:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:33 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 0x7f29ee557f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', '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 07:50:33 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 07:50:33 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 07:50:33 INFO - Using env: {'DISPLAY': ':0', 07:50:33 INFO - 'HOME': '/home/cltbld', 07:50:33 INFO - 'LANG': 'en_US.UTF-8', 07:50:33 INFO - 'LANGUAGE': 'en_US:en', 07:50:33 INFO - 'LOGNAME': 'cltbld', 07:50:33 INFO - 'MAIL': '/var/mail/cltbld', 07:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:33 INFO - 'MOZ_NO_REMOTE': '1', 07:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:33 INFO - 'NO_EM_RESTART': '1', 07:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:33 INFO - 'PWD': '/builds/slave/test', 07:50:33 INFO - 'SHELL': '/bin/bash', 07:50:33 INFO - 'SHLVL': '1', 07:50:33 INFO - 'TERM': 'linux', 07:50:33 INFO - 'TMOUT': '86400', 07:50:33 INFO - 'USER': 'cltbld', 07:50:33 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:33 INFO - '_': '/tools/buildbot/bin/python'} 07:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:50:33 INFO - Cleaning up... 07:50:33 INFO - Return code: 0 07:50:33 INFO - Running post-action listener: _resource_record_post_action 07:50:33 INFO - Running post-action listener: _start_resource_monitoring 07:50:33 INFO - Starting resource monitoring. 07:50:33 INFO - [mozharness: 2016-09-17 14:50:33.371170Z] Finished create-virtualenv step (success) 07:50:33 INFO - [mozharness: 2016-09-17 14:50:33.371675Z] Running install step. 07:50:33 INFO - Running pre-action listener: _resource_record_pre_action 07:50:33 INFO - Running main action method: install 07:50:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:33 INFO - Reading from file tmpfile_stdout 07:50:33 INFO - Detecting whether we're running mozinstall >=1.0... 07:50:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:50:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:50:33 INFO - Reading from file tmpfile_stdout 07:50:33 INFO - Output received: 07:50:33 INFO - Usage: mozinstall [options] installer 07:50:33 INFO - Options: 07:50:33 INFO - -h, --help show this help message and exit 07:50:33 INFO - -d DEST, --destination=DEST 07:50:33 INFO - Directory to install application into. [default: 07:50:33 INFO - "/builds/slave/test"] 07:50:33 INFO - --app=APP Application being installed. [default: firefox] 07:50:33 INFO - mkdir: /builds/slave/test/build/application 07:50:33 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'] 07:50:33 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 07:50:50 INFO - Reading from file tmpfile_stdout 07:50:50 INFO - Output received: 07:50:50 INFO - /builds/slave/test/build/application/firefox/firefox 07:50:50 INFO - Running post-action listener: _resource_record_post_action 07:50:50 INFO - [mozharness: 2016-09-17 14:50:50.654083Z] Finished install step (success) 07:50:50 INFO - [mozharness: 2016-09-17 14:50:50.654328Z] Running run-tests step. 07:50:50 INFO - Running pre-action listener: _resource_record_pre_action 07:50:50 INFO - Running main action method: run_tests 07:50:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:50:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:50:50 INFO - Python 2.7.3 07:50:50 INFO - Return code: 0 07:50:50 INFO - grabbing minidump binary from tooltool 07:50:50 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')]} 07:50:50 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 0x2c4c930>, '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 0x2c4d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4dcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:50:50 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 07:50:50 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 07:50:50 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 07:50:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:50:50 INFO - Return code: 0 07:50:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:50:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:50:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:50:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:50:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:50:50 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/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:50:50 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/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:50:50 INFO - Using env: {'DISPLAY': ':0', 07:50:50 INFO - 'HOME': '/home/cltbld', 07:50:50 INFO - 'LANG': 'en_US.UTF-8', 07:50:50 INFO - 'LANGUAGE': 'en_US:en', 07:50:50 INFO - 'LOGNAME': 'cltbld', 07:50:50 INFO - 'MAIL': '/var/mail/cltbld', 07:50:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:50:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:50 INFO - 'MOZ_NO_REMOTE': '1', 07:50:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:50:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:50 INFO - 'NO_EM_RESTART': '1', 07:50:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:50 INFO - 'PWD': '/builds/slave/test', 07:50:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:50:50 INFO - 'SHELL': '/bin/bash', 07:50:50 INFO - 'SHLVL': '1', 07:50:50 INFO - 'TERM': 'linux', 07:50:50 INFO - 'TMOUT': '86400', 07:50:50 INFO - 'USER': 'cltbld', 07:50:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376', 07:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:50 INFO - '_': '/tools/buildbot/bin/python'} 07:50:50 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/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:50:50 INFO - mozversion application_buildid: 20160917030427 07:50:50 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 07:50:50 INFO - mozversion application_display_name: Nightly 07:50:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:50:50 INFO - mozversion application_name: Firefox 07:50:50 INFO - mozversion application_remotingname: firefox 07:50:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:50:50 INFO - mozversion application_vendor: Mozilla 07:50:50 INFO - mozversion application_version: 51.0a1 07:50:50 INFO - mozversion platform_buildid: 20160917030427 07:50:50 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 07:50:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:50:50 INFO - mozversion platform_version: 51.0a1 07:50:50 INFO - using testdate: 1474123850 07:50:50 INFO - actual date: 1474123850 07:50:50 INFO - starting webserver on 'localhost:56747' 07:50:50 INFO - SUITE-START | Running 2 tests 07:50:50 INFO - TEST-START | dromaeo_css 07:50:50 INFO - operating with platform_type : linux_ 07:50:50 INFO - Initialising browser for dromaeo_css test... 07:50:50 INFO - TEST-INFO | started process 7407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXV2wS7/profile http://localhost:56747/getInfo.html) 07:50:55 INFO - PROCESS | 7407 | __metrics Screen width/height:1600/1200 07:50:55 INFO - PROCESS | 7407 | colorDepth:24 07:50:55 INFO - PROCESS | 7407 | Browser inner width/height: 1024/697 07:50:55 INFO - PROCESS | 7407 | __metrics 07:50:56 INFO - PROCESS | 7407 | 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" 07:50:56 INFO - PROCESS | 7407 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:56 INFO - PROCESS | 7407 | console.error: PushService: 07:50:56 INFO - PROCESS | 7407 | stateChangeProcessEnqueue: Error transitioning state 07:50:56 INFO - PROCESS | 7407 | UnknownError 07:50:57 INFO - TEST-INFO | 7407: exit 0 07:50:57 INFO - Browser initialized. 07:50:57 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:57 INFO - TEST-INFO | started process 7489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXV2wS7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:09 INFO - PROCESS | 7489 | RSS: Main: 172724224 07:51:09 INFO - PROCESS | 7489 | 07:51:14 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:51:19 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:51:26 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:51:28 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:51:34 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 07:51:52 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:52:00 INFO - PROCESS | 7489 | 1474123920791 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] 07:52:13 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:52:22 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.10006 seconds. 07:52:25 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:52:38 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 07:52:43 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.05009 seconds. 07:52:47 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:52:55 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 07:52:59 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.08336 seconds. 07:53:19 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:53:24 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 07:53:26 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.4334 seconds. 07:53:29 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 07:53:35 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:53:46 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:54:10 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:54:10 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56747/tests/dromaeo/cssquery-ext.html) 07:54:11 INFO - PROCESS | 7489 | RSS: Main: 320401408 07:54:11 INFO - PROCESS | 7489 | 07:54:14 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 07:54:28 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:54:30 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:55:25 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:55:41 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 07:55:49 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:56:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:56:13 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:56:15 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:56:20 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:56:27 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:56:51 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:56:53 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:57:05 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:57:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:57:13 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-ext.html (next: http://localhost:56747/tests/dromaeo/cssquery-jquery.html) 07:57:14 INFO - PROCESS | 7489 | RSS: Main: 267362304 07:57:14 INFO - PROCESS | 7489 | 07:57:19 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:57:31 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:57:33 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:57:35 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:57:39 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:58:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:58:29 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:58:35 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:58:39 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:59:21 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:59:23 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:59:34 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:59:36 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:59:42 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:59:48 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:59:54 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:00:10 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 08:00:16 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56747/tests/dromaeo/cssquery-mootools.html) 08:00:16 INFO - PROCESS | 7489 | RSS: Main: 214556672 08:00:16 INFO - PROCESS | 7489 | 08:00:20 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:00:45 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:00:49 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:00:55 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:01:04 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:01:06 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:01:09 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:01:16 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:01:26 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03345 seconds. 08:01:46 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:01:56 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:01:58 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:02:00 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:02:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:02:21 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:02:27 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:02:28 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:02:30 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:02:42 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:03:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:03:15 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:03:17 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:03:19 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56747/tests/dromaeo/cssquery-prototype.html) 08:03:19 INFO - PROCESS | 7489 | RSS: Main: 245800960 08:03:19 INFO - PROCESS | 7489 | 08:03:48 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:04:08 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:04:17 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:04:32 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:05:04 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:05:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:05:19 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:05:30 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:05:58 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:06:08 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:06:12 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:06:18 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:06:22 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56747/tests/dromaeo/cssquery-yui.html) 08:06:22 INFO - PROCESS | 7489 | RSS: Main: 213798912 08:06:22 INFO - PROCESS | 7489 | 08:06:28 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:06:31 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:06:42 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:06:59 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:07:05 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:07:07 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:07:13 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 08:07:32 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:08:04 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:08:25 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:08:32 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:08:37 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:08:40 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:08:43 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:08:53 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:09:17 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:09:22 INFO - PROCESS | 7489 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:09:26 INFO - PROCESS | 7489 | Cycle 1(1): loaded http://localhost:56747/tests/dromaeo/cssquery-yui.html (next: http://localhost:56747/tests/dromaeo/cssquery-dojo.html) 08:09:26 INFO - PROCESS | 7489 | RSS: Main: 283893760 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | __start_tp_report 08:09:26 INFO - PROCESS | 7489 | _x_x_mozilla_page_load 08:09:26 INFO - PROCESS | 7489 | _x_x_mozilla_page_load_details 08:09:26 INFO - PROCESS | 7489 | |i|pagename|runs| 08:09:26 INFO - PROCESS | 7489 | |0;dojo.html;3022.576839242506;3050.984745076275;3055.3889222155576;3083.121310426529;3113.190570451682;5054.494550544953;5147.22791581263;5175.896482070361;5306.761195746191;5337.119375303075;5212.687238765677;5233.345831771028;5268.420473747894;5275.340582427187;5282.313299271099;4731.597814185799;4811.831585894494;4815.398075240595;4823.445303790074;4906.803727850882;4900.950990490103;4920.532549407801;4950.727709975949;4966.478519755427;4988.326575912241;4720.669553131283;4747;4750.45369782476;4764.023375208074;4930.482299358569;4775.474697783241;4798.232282834764;4860.659753817235;4874.780634871439;4876.975615121902;4723.76381180943;4726.929096063562;4757.9286310705365;4767.118083154593;4814.06125805468;5209.453007434203;5211.6941469883905;5212.791488340462;5212.973935130338;5282.154855223146;5396.298481197419;5456.208849716769;5567.498925096761;5630.605857589971;5631.746571404296;5683.8010669626365;5683.857903552444;5688.658680479184;5693.089105743061;5744.425557444264;5281.445448227919;5291.28567643367;5291.523762861322;5292.947070529322;5357.892842143136;5568.693721845338;5574.637648552831;5587;5610.551155907518;5737.627054241544;5567;5602.299712535933;5606.747696353674;5652;5668.234788303609;5693.544516438675;5693.68684722336;5695.145728140729;5696.51579615729;5702.686352250583;5363.383210930715;5420.91868621971;5557.694326812064;5667.574931880125;5670.914936275959;5616.466435688603;5662.858428539237;5670.5463562914865;5736.971315143398;5737.426257374229;4399.912001759947;4402.317640765622;4408.206522825858;4451.821927122944;4457.153140903218;3850.942235866464;3857.7685338879196;3906.882793516199;3925.1953349563273;3977.7215594908657;5327.760250788724;5365.08793505105;5374.1938709193155;5382.596305277119;5402.864928376822;5693.202951586781;5695.145728140812;5700.686462244616;5704.8859022819315;5721;5153.716545589954;5340.305760251143;5397.865053373697;5404.972975135178;5460.372057213472;4799.016201678647;4895.7062576245535;4907.803687852517;4938.061768264019;4981.501849815062;5873.471387575053;5887.293524777054;5888.970555147197;5889.440503152194;5900;5692.0608099663095;5701.486866182064;5704.401037890962;5705.429457054262;5706.771729130788;5603.775848966159;5606.943930560642;5745.770169193185;5748;5793.623414478046;5506.752196151103;5560.388357280615;5564.165375193753;5564.415736347625;5625.381208067028;5642.915356269741;5650.802221922213;5653.462921022497;5655;5655.377908429987;5243.89512209746;5285.8678533035445;5440.918386224131;5462.754176062007;5495.6153069284765;5481.725913704219;5509;5511.559075274049;5525.972370138124;5547.41752116022;5410.675359478444;5423.484768946943;5426.484483974016;5427.4301198373605;5432.809851655173;5247.317848679647;5289.947100528945;5488.451154884479;5502.422245664147;5512.310961129859;5401;5449.155380915964;5456.890862182813;5498.120300751861;5517.779288828402 08:09:26 INFO - PROCESS | 7489 | |1;ext.html;568.9317281926169;619.8605313804393;633.7750098714953;640.7821340744148;662.443547420167;34965.47551786722;42439.78780106099;42842;42868.57131428685;46418.303725444064;44229.115417691624;44605.7769711151;44642.10715785682;44676.3298550522;44801.1039779205;8836.204741573258;8979.371443998923;8984.281257499417;8987.820243595124;8988.370814043019;8764.649414023432;8851.822963540726;8853.84899963004;8855.33584981129;8858.86711699325;8931.062238464965;9000.14498622633;9002;9002.684906028258;9003.324750643695;8901.109889011112;8902.109789021111;8923.196912277926;8942.597583108776;8946;8722.86915696265;8751.649934002633;8757.51833649149;8762.780930476725;8766.824663506693;41762;42086.57913420888;42110.78944605288;42156.578434215575;42170;3459.619441861418;3488.5639295088113;3611.4221724524214;3637.3270944875285;3768.0956570423177;31244.375112497622;31362.843185783924;31677.36645267105;31870.840645796856;32313.1921701957;3910.8826735197704;3952.288588054149;3969.2260009298197;3979.801009949491;4078.1435898461064;348644;350259;350970;351041;351536;56625.43374566284;57018.7149064252;57023.42976570181;57042.7147864258;57046.71476642673;52689;53991;59254.40745592489;60037;60097.39902600918;16338.428155014517;17275;17279.481615551555;17292;17301.394451194148;12140.939295303468;12160.878391215974;12173.756524869454;12195.512179512898;12201;15226.314815833313;15733.606659833596;16648;17082.316707331815;17253.15322971721;6633.601983880886;6860.759873404407;6900.791132123587;6908.274631163756;6977.592730508068;938.5776400619749;948.7248697877676;1020.2144348851343;1171.6602185366319;1178.6169494914186;29952;31226;31264.843675781474;31445.528317075263;31705.20736981594;1100.4387762241315;1103.779244151173;1129.7119234595125;1177.0995188680665;1196.6350263169816;16684.74972875408;16754.497365079067;16788.748168777478;16918.238679259463;16923.20767073419;503251;507990;508421;508533;509846;56030.71984640132;56281;56399.15401268984;56427.71786141125;56428.717856410454;1335.1721932401972;1376.2224343246035;1389.5275606293908;1393.101449565025;1395.413926151019;29663.555046673886;30833.537496937115;31182.844085779427;31285;31442.842785785924;50699.239511408094;51402.74298628483;51419;51425.48574514207;51429.48570514247;318803;319490;319913;320152;320279;33532.34775396872;34577.48133777945;35944.460833087;41006.79496602498;41466.37800432936;40602.79698601488;45431;45874.77062614666;46126.30810537912;46484.30273545832;39102.6089739099;39220.80389598034;39576.87616229106;39812.40281395723;40782.388264176654;39370;39888.401673975495;40014;40139.598604014755;41466.792666036476;38585;39325.41011884881;39424.40863387109;39718;40144.59855401408;33890;35532.46701299431;36364.81817590895;36457;36545.45181822328;27381.72618273792;27413.314667132683;27556.311092222855;27557.44885102327;27597.172084837486 08:09:26 INFO - PROCESS | 7489 | |2;jquery.html;369.9648533389327;380.24900820878753;394.9150932549502;397.3562828218287;417.60953508469584;14393.352299146538;19016.33442829503;19170.61658766824;20576.588468230628;20631.896840515776;19002.61994760104;19056.142473588687;19602.31391901284;19790.406287811387;19795;5311.256424100619;5502.58730595205;5511.779528818843;5511.807086751965;5513.8345849624575;5248.422673505911;5350.91973620394;5385.946140538584;5386.73066346683;5429.918551221735;5332.493413125766;5394.298741163643;5400.081986062379;5402.864928376783;5404.189371594272;5176;5338.67967921924;5343.919841202365;5370.865728356784;5376.193570964348;5225.320708307896;5226.947730522684;5235.057689615867;5236.162214045772;5236.73816309183;14562.126272423582;18791.436256912315;19072.427827165207;23127.53744925109;23304.989427273216;2174.6550777404705;2185.0777853752393;2300.378897697629;2397.5204959008092;2574.7167811540717;10241.436720980342;10359.378437293786;10406.115480184193;10817.188710846718;10895.564177432892;1910.4650697804636;1922.356010736405;1924.6728056230475;1933.4103098554936;1944.36808037387;78559.60760074192;82255.58872205601;83407.5829620854;84057;86553;11219.400045981109;11294.830577541343;11395.829062564067;11543.711407214887;11546.940060883873;16111.27499262536;16128.75806862898;16132.838671613134;16137.919310403373;16139.757903631455;7981.321587665114;8121.75634730959;8183.181681831769;8270.503769773833;8346.207110324509;7573.734919277909;7656.73201437947;7838.608069596611;7874.645640946172;7875.881861772078;4735.857924262277;4787.042591481648;4867.878303042452;4888.706677599355;4999.125153098266;2021.9292324768858;2035.369035598969;2074.0209196055466;2078.5946740385784;2089;885.6767279942775;961.7932144588807;974.6247694637616;1017.1201910347496;1019.5208252121491;18981;19592.21631134767;19778.802211977985;19836.50408739793;20209.898950505154;985.4383001689112;1003.508280942333;1021.213665477578;1047.0158051431629;1064.8189807732704;10314.48427578618;10818.02637762605;10823.783524329468;10847.977837679042;11031.889681103086;437394;444093;450408;452000;456948;89044.5547772257;89118;89786;90071.54964225137;90238;985.2856678907877;1014.6398028699705;1022.6676330192718;1035.4460363705382;1039.734867608755;16558.58603534873;17049.744253836452;17399.304027838745;17400.47798566045;17408.129593520527;38987.17724398162;40316.79841600773;42644.786776067165;43185.7840710807;44267.55732442763;57345;57961.71019145046;58744.412555875606;58871;59478.405215947285;5521.889562208814;7790.610469476617;8134.552599607078;8562.058173600773;8669.523176225115;12234.632961011184;12956;13248;13947.51183708606;14056.859431405965;11390.943045284721;11403.828942566033;11641.53433862636;12027.939860300643;12299.877001229874;11253.831192532283;11634.476448559666;12039;12053.674095849625;12374.195677280948;11233.71915702114;11774.82337764917;11831;12250.387480626112;12528.87471125277;7777.766666999999;8686.782830429038;8910.86633700508;9094.408863423856;9132.726018219464;10790.920907909147;11193.160512961398;11412.543498259976;11512.59705910289;11682.357470338873 08:09:26 INFO - PROCESS | 7489 | |3;mootools.html;1360.1974834847438;1471.2423102102418;1498.423107103765;1625.7805196298496;1789.1769785898493;7817;8364.790880227998;8646.265067469258;8666.73999780006;8666.826663466734;8473.87289190661;8622.698205562807;8641.740747777576;8873.822523549525;8881.200691937724;2390.9880450597766;2423.2245681381964;2426.5268272686835;2427.587310157269;2429.6598476213344;2359.162497313455;2394.0902457066377;2437.9756202438016;2437.987810060947;2441.5483135619966;2311.4105902994797;2316.4903721181313;2318.8608683479047;2356.057576969209;2362.3031205794246;2314.6296592545114;2350.71791385034;2358.445765245166;2363.3737059679197;2363.9054437822465;2310.9653355199684;2334.4280651240383;2342.1685301717816;2343.1915988983774;2347.859128452298;8425.199606037479;8475.830483390358;8483.448575842613;8822.514761688104;8876.822463550758;5246.711430871301;5468;5486.149646804752;5499.422560631157;5503.036968530531;3352.580927384077;3386.72906167506;3390.440577304742;3406.1654894550747;3458.5330980317585;1080.524569189554;1108.3571528513444;2007.6988451732357;2056.4961584411917;2103.516191276015;12088.274703517729;12178.939105304506;12242.204256723284;12267.202631828814;12275.447604857804;4701.1537923174155;4760.0509970706125;4792.257200133985;4869.318295438641;4887.218045112765;10463.476826158661;10477;10480.580776768997;10482.947585262025;10488.423136727553;5394.838154855361;5428.9185662215095;5462.399136095027;5473.808416705396;5486.478784515464;4268.466441694788;4374.496932852699;4745.928811067837;4819.542143496362;4967.056259310792;4004.6996475264473;4037;4104.507459104927;4189.601987811153;4189.664826813909;1320.0759468372178;1336.3318340829585;1338.4713038349798;1357.4027427931678;1357.6673714939902;798.4211446701212;859.2524503681836;883.6112110671413;898.7124120281447;911.9042500537348;5558.416366281563;5824.388439213906;5899.056151015817;5904.704764761831;5969.626985793292;505.88617561049205;662.9602223866583;1020.7244044108049;1022.483645758887;1040.1159014837328;3757.793321367351;3883.902902427462;3897.1231472918366;3916.2950668879876;3939.3893946438347;10802.243842931077;11145;11170.776584468265;11176.27354221973;11178.832317515244;11062.778744425066;11064.114870810316;11065.889341106486;11099.778004439866;11120.054795342325;7874.606269686607;8197.83604327922;8205.548694821722;8216.794580135544;8326.875096873551;5861.120831875252;5903.085021821625;6034.849128771816;6061.484773794277;6124.38756124384;10160.796784064425;10172.440515771703;10222.182225422097;10288.12550933179;10318.43248621303;10075.042870927251;10161.339512931636;10208.285420020527;10247.910849645865;10436.425996569958;7014.403775679124;7136.60748658808;7229.421646268391;7611.200823913409;7839.372850171859;8522.36082293818;8858.822823543622;8862.42394244372;8883.111688831064;8888.688895888872;7380.483366164317;7389.519681220703;7589.9374087626675;7599.354054905395;7600.771976840703;7195.8560828784175;7384.224656410999;7513.774586762406;7672.654730537028;7751.961240193763;6736.427403670743;6851.109355783717;6852.657367131723;6854.588724676535;6863.347981941708;4868.780904859219;4903.289023091703;4989.176785830297;5026.271190677405;5103.285540024473;4871.3667223260745;4990.251462280542;5009.148444764472;5015.724135172601;5064.772085256246 08:09:26 INFO - PROCESS | 7489 | |4;prototype.html;305.97552195824335;317.43179708322106;320.4584252613082;325.09461150695313;329.1901921273667;4964.826231081913;5142.228665700138;5174.43081261061;5213;5214.661047031945;4993.825216117437;5072.9492705072935;5144.25408315794;5161.380634323877;5162.328897243353;4118.608732170454;4120.928558574762;4122.50529936408;4129.504459464861;4133.297339452301;3845.538535375759;4050.521559630743;4136.110736191718;4138.2758017347;4170.4995400551825;4019.3970904364432;4024.738392004525;4054.148628787958;4058.3506638937774;4064.7561146331313;3933.8985084176334;3968.987908083449;3993.1414745829497;3994.380870965005;4046.150308435232;3466.5146879436647;3534.098804804784;3565.6612621801146;3580.7135429165605;3596.183927116789;5033.748312584356;5034.647574669774;5043.974780126113;5051.621128415383;5068.290439338495;4646.605038571725;4652.255639097762;4677.50886156952;4680.906381872377;4681.133990211788;5300.92048619271;5343.438938911436;5371.785128594852;5400.280443320006;5447.373552041487;3876.089119057019;3881.6894648428056;3886;3888.747231429948;3906.1015966327905;5605.607607467479;5626.887462250773;5636.915446268309;5647.774089036475;5696.202531645573;3924.9215015700097;4012.598740126022;4013.337799263089;4019.1358857845703;4021.879343619696;4039.6566291864933;4049.919001619951;4074.9796251018556;4079.347304431277;4082.3672330788777;4581.2440947243995;4611.5619016193405;4632.698874573142;4668.6965347253;4679.695819771704;5241.842744717665;5433.483819037185;5444.686008446473;5457.317835270591;5470.945290547043;1373.9175649460865;1387.9583612491642;1394.9023568350322;1396.525181438316;1399.846016938136;865.5499140447058;883.7834730491071;890.6708956079934;895.6238379880466;902.5803001604154;4568.862934111982;4584.701994370355;4625.444946606362;4678.836240731558;4692.85921422351;4848.684835485682;4852.344933434012;4878.829240976549;4886.389201349832;4952.801887924515;4403.735775853459;4773.474917759044;4775.450823155312;4787.018661174451;4788.784504697297;2581.1482210870367;2594.171109367891;2602.921912342633;2609;2620.2139358192467;5028.17035189197;5049.873753156127;5056.84829455117;5057.924131138036;5077.238414237894;4816.710997340101;4973.975130124326;4996.550310472075;5016.673916195508;5034.723090230073;5012.8496145115705;5034.647574669812;5055.317532133188;5063.924041139312;5081.1707785197295;4886.389201349832;4897.363342765418;4906.877328066684;4931.408231012301;4940.925886111783;4596.115592607015;4829.975850120727;4897.240927656219;4921.129970611145;4949.183384741621;5047.823326183566;5050.954363482795;5062.620303477328;5083.644144909968;5088.643794934319;3620.6741393274733;3778.4899038630533;3859.691224702073;3898.78556679374;3949.861754838567;5527.39198688152;5535.446455354432;5538.9169162461785;5539;5551.333839939233;5331.693735034942;5400.162974738922;5423.945760542502;5429.475293988315;5460.426655201147;5214.478552144755;5339.893202136013;5344.144936810092;5365.517103460707;5385.111456609616;5337.786488540415;5354.812581559627;5376.784928602812;5377.327834020747;5377.435369286325;5180.663256888291;5416.51251387377;5436.972815135899;5461.098918678374;5474.972625136849;5399.514043736082;5427.24018637399;5434.592405569677;5435.646682965595;5437.184422336682 08:09:26 INFO - PROCESS | 7489 | |5;yui.html;716.4340171264698;728.646606395898;763.7479631721533;787.6927998080746;792.3898598079483;14788.112713237213;15390;16115.630171435441;16137.273822677964;16160.757588636165;14100.36548355324;14171.716565668681;14185.858141418583;14210.360533775978;14222.075565088262;802.2619190344897;819.3076850061689;820.36421773126;822.116225058065;822.8930239068912;675.2572170612336;687.9793606191822;798.648594618369;816.9387295952828;824.0194168938973;859.0979471554874;864.3560547392202;865.8008658008662;866.1165611076698;866.2117473099481;840.2437805974611;846.7121178799207;846.9322454203649;847.0513025411578;847.5338563789891;508.1640701046794;523.1890569617117;524.6065450911816;524.7009204753292;613.1140501974648;14407.639809004753;14876.181810000446;14886.627834304121;14905.478308259268;14908.552743417715;13397;13473.461061557526;13659.317034148253;13955.092918960336;14064.71870562583;12223.755524889453;12625;12651.177673451288;12687.365631718378;13120.868791312156;4199.454070970784;4224.957750422487;4258.680598955059;4268.231718290706;4431.844885429026;62267.6886615564;62288.37711622871;62712.68643656753;72218.63890680566;79847.6007619964;10749.500998003992;10869.945650271698;10907.563697452171;10960.342379457257;11005.119590432747;19196.90401548011;19202.711959320623;19209.80790192108;19224.519387015436;19235.711464328047;8052.758417247374;8420.53687047206;8421.873671894926;8597.097304782908;8607.913920860836;4671.509491503343;4741.95258047422;4910.508949105061;5060.822871199491;5072.67027643202;3209.7753157278767;3303.3888730584918;3316.6185888623118;3317.1209629448217;3317.6018877734828;573.6414740787009;583.2243116654839;583.7635757518127;594.8037147741235;595.3718826637938;603.8097999130348;616.8797084568466;654.1234745441132;661.9139511863523;690.8825499665069;8040.15578364275;8135.877961830577;8245.051819040767;8279.296259817984;8334.749957501284;634.6985182038495;636.2015670333701;651.26407159909;659.4131223211346;687.3504538211442;3818.3317919364;3897.7271590988357;3923.4114882767244;4020.798960051986;4032.3951407288555;68735.31264687289;68938.65530672415;70220.2977970224;91570;95710;57021;57094.7145264271;57631;89799.55100224457;93108;77453;80154;80592;82436.22238553035;82943;8233.300169485421;8436.072032076589;8567.27178189861;8672.696455624024;8791.780205494913;47435.52564474449;47895;48148.75925620349;49117;51231;71305;74091.62954185194;74119.25880741124;74132.62933685297;77196;10932.0707739843;12265.877341226473;12326.938365308117;12331.938340308241;13497.055206135476;13956.930215348859;13962.232077235443;14387.136771793726;14593.635159120682;14891.851081489047;12178.695532611755;12597.307148106576;12619.30593817349;12646.683832903884;12780.488780448677;13082.934585327394;13280;13525.926515031235;13531.75237942867;13702.86297137016;12787.360631968178;13517;13580.388882500509;13766.380512876744;13964.092333998258;10691.465426728788;11029.779404411707;11109.944450277697;11228.494717737558;11250.381229032155;9521.285903557122;10605.840912386475;10922.18083643714;10946.616868409568;11075.390853503135 08:09:26 INFO - PROCESS | 7489 | __end_tp_report 08:09:26 INFO - PROCESS | 7489 | __start_cc_report 08:09:26 INFO - PROCESS | 7489 | _x_x_mozilla_cycle_collect,1143 08:09:26 INFO - PROCESS | 7489 | __end_cc_report 08:09:26 INFO - PROCESS | 7489 | __startTimestamp1474124966285__endTimestamp 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | ------- Summary: start ------- 08:09:26 INFO - PROCESS | 7489 | Number of tests: 6 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#0] dojo.html Cycles:165 Average:5240.63 Median:5420.92 stddev:577.26 (10.6%) stddev-sans-first:552.18 08:09:26 INFO - PROCESS | 7489 | Values: 3022.6 3051.0 3055.4 3083.1 3113.2 5054.5 5147.2 5175.9 5306.8 5337.1 5212.7 5233.3 5268.4 5275.3 5282.3 4731.6 4811.8 4815.4 4823.4 4906.8 4901.0 4920.5 4950.7 4966.5 4988.3 4720.7 4747.0 4750.5 4764.0 4930.5 4775.5 4798.2 4860.7 4874.8 4877.0 4723.8 4726.9 4757.9 4767.1 4814.1 5209.5 5211.7 5212.8 5213.0 5282.2 5396.3 5456.2 5567.5 5630.6 5631.7 5683.8 5683.9 5688.7 5693.1 5744.4 5281.4 5291.3 5291.5 5292.9 5357.9 5568.7 5574.6 5587.0 5610.6 5737.6 5567.0 5602.3 5606.7 5652.0 5668.2 5693.5 5693.7 5695.1 5696.5 5702.7 5363.4 5420.9 5557.7 5667.6 5670.9 5616.5 5662.9 5670.5 5737.0 5737.4 4399.9 4402.3 4408.2 4451.8 4457.2 3850.9 3857.8 3906.9 3925.2 3977.7 5327.8 5365.1 5374.2 5382.6 5402.9 5693.2 5695.1 5700.7 5704.9 5721.0 5153.7 5340.3 5397.9 5405.0 5460.4 4799.0 4895.7 4907.8 4938.1 4981.5 5873.5 5887.3 5889.0 5889.4 5900.0 5692.1 5701.5 5704.4 5705.4 5706.8 5603.8 5606.9 5745.8 5748.0 5793.6 5506.8 5560.4 5564.2 5564.4 5625.4 5642.9 5650.8 5653.5 5655.0 5655.4 5243.9 5285.9 5440.9 5462.8 5495.6 5481.7 5509.0 5511.6 5526.0 5547.4 5410.7 5423.5 5426.5 5427.4 5432.8 5247.3 5289.9 5488.5 5502.4 5512.3 5401.0 5449.2 5456.9 5498.1 5517.8 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#1] ext.html Cycles:180 Average:55893.44 Median:39951.20 stddev:106063.64 (265.5%) stddev-sans-first:106279.83 08:09:26 INFO - PROCESS | 7489 | Values: 568.9 619.9 633.8 640.8 662.4 34965.5 42439.8 42842.0 42868.6 46418.3 44229.1 44605.8 44642.1 44676.3 44801.1 8836.2 8979.4 8984.3 8987.8 8988.4 8764.6 8851.8 8853.8 8855.3 8858.9 8931.1 9000.1 9002.0 9002.7 9003.3 8901.1 8902.1 8923.2 8942.6 8946.0 8722.9 8751.6 8757.5 8762.8 8766.8 41762.0 42086.6 42110.8 42156.6 42170.0 3459.6 3488.6 3611.4 3637.3 3768.1 31244.4 31362.8 31677.4 31870.8 32313.2 3910.9 3952.3 3969.2 3979.8 4078.1 348644.0 350259.0 350970.0 351041.0 351536.0 56625.4 57018.7 57023.4 57042.7 57046.7 52689.0 53991.0 59254.4 60037.0 60097.4 16338.4 17275.0 17279.5 17292.0 17301.4 12140.9 12160.9 12173.8 12195.5 12201.0 15226.3 15733.6 16648.0 17082.3 17253.2 6633.6 6860.8 6900.8 6908.3 6977.6 938.6 948.7 1020.2 1171.7 1178.6 29952.0 31226.0 31264.8 31445.5 31705.2 1100.4 1103.8 1129.7 1177.1 1196.6 16684.7 16754.5 16788.7 16918.2 16923.2 503251.0 507990.0 508421.0 508533.0 509846.0 56030.7 56281.0 56399.2 56427.7 56428.7 1335.2 1376.2 1389.5 1393.1 1395.4 29663.6 30833.5 31182.8 31285.0 31442.8 50699.2 51402.7 51419.0 51425.5 51429.5 318803.0 319490.0 319913.0 320152.0 320279.0 33532.3 34577.5 35944.5 41006.8 41466.4 40602.8 45431.0 45874.8 46126.3 46484.3 39102.6 39220.8 39576.9 39812.4 40782.4 39370.0 39888.4 40014.0 40139.6 41466.8 38585.0 39325.4 39424.4 39718.0 40144.6 33890.0 35532.5 36364.8 36457.0 36545.5 27381.7 27413.3 27556.3 27557.4 27597.2 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#2] jquery.html Cycles:180 Average:27742.03 Median:12713.96 stddev:74164.32 (583.3%) stddev-sans-first:74343.89 08:09:26 INFO - PROCESS | 7489 | Values: 370.0 380.2 394.9 397.4 417.6 14393.4 19016.3 19170.6 20576.6 20631.9 19002.6 19056.1 19602.3 19790.4 19795.0 5311.3 5502.6 5511.8 5511.8 5513.8 5248.4 5350.9 5385.9 5386.7 5429.9 5332.5 5394.3 5400.1 5402.9 5404.2 5176.0 5338.7 5343.9 5370.9 5376.2 5225.3 5226.9 5235.1 5236.2 5236.7 14562.1 18791.4 19072.4 23127.5 23305.0 2174.7 2185.1 2300.4 2397.5 2574.7 10241.4 10359.4 10406.1 10817.2 10895.6 1910.5 1922.4 1924.7 1933.4 1944.4 78559.6 82255.6 83407.6 84057.0 86553.0 11219.4 11294.8 11395.8 11543.7 11546.9 16111.3 16128.8 16132.8 16137.9 16139.8 7981.3 8121.8 8183.2 8270.5 8346.2 7573.7 7656.7 7838.6 7874.6 7875.9 4735.9 4787.0 4867.9 4888.7 4999.1 2021.9 2035.4 2074.0 2078.6 2089.0 885.7 961.8 974.6 1017.1 1019.5 18981.0 19592.2 19778.8 19836.5 20209.9 985.4 1003.5 1021.2 1047.0 1064.8 10314.5 10818.0 10823.8 10848.0 11031.9 437394.0 444093.0 450408.0 452000.0 456948.0 89044.6 89118.0 89786.0 90071.5 90238.0 985.3 1014.6 1022.7 1035.4 1039.7 16558.6 17049.7 17399.3 17400.5 17408.1 38987.2 40316.8 42644.8 43185.8 44267.6 57345.0 57961.7 58744.4 58871.0 59478.4 5521.9 7790.6 8134.6 8562.1 8669.5 12234.6 12956.0 13248.0 13947.5 14056.9 11390.9 11403.8 11641.5 12027.9 12299.9 11253.8 11634.5 12039.0 12053.7 12374.2 11233.7 11774.8 11831.0 12250.4 12528.9 7777.8 8686.8 8910.9 9094.4 9132.7 10790.9 11193.2 11412.5 11512.6 11682.4 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#3] mootools.html Cycles:180 Average:5802.65 Median:4723.54 stddev:3279.92 (69.4%) stddev-sans-first:3272.12 08:09:26 INFO - PROCESS | 7489 | Values: 1360.2 1471.2 1498.4 1625.8 1789.2 7817.0 8364.8 8646.3 8666.7 8666.8 8473.9 8622.7 8641.7 8873.8 8881.2 2391.0 2423.2 2426.5 2427.6 2429.7 2359.2 2394.1 2438.0 2438.0 2441.5 2311.4 2316.5 2318.9 2356.1 2362.3 2314.6 2350.7 2358.4 2363.4 2363.9 2311.0 2334.4 2342.2 2343.2 2347.9 8425.2 8475.8 8483.4 8822.5 8876.8 5246.7 5468.0 5486.1 5499.4 5503.0 3352.6 3386.7 3390.4 3406.2 3458.5 1080.5 1108.4 2007.7 2056.5 2103.5 12088.3 12178.9 12242.2 12267.2 12275.4 4701.2 4760.1 4792.3 4869.3 4887.2 10463.5 10477.0 10480.6 10482.9 10488.4 5394.8 5428.9 5462.4 5473.8 5486.5 4268.5 4374.5 4745.9 4819.5 4967.1 4004.7 4037.0 4104.5 4189.6 4189.7 1320.1 1336.3 1338.5 1357.4 1357.7 798.4 859.3 883.6 898.7 911.9 5558.4 5824.4 5899.1 5904.7 5969.6 505.9 663.0 1020.7 1022.5 1040.1 3757.8 3883.9 3897.1 3916.3 3939.4 10802.2 11145.0 11170.8 11176.3 11178.8 11062.8 11064.1 11065.9 11099.8 11120.1 7874.6 8197.8 8205.5 8216.8 8326.9 5861.1 5903.1 6034.8 6061.5 6124.4 10160.8 10172.4 10222.2 10288.1 10318.4 10075.0 10161.3 10208.3 10247.9 10436.4 7014.4 7136.6 7229.4 7611.2 7839.4 8522.4 8858.8 8862.4 8883.1 8888.7 7380.5 7389.5 7589.9 7599.4 7600.8 7195.9 7384.2 7513.8 7672.7 7752.0 6736.4 6851.1 6852.7 6854.6 6863.3 4868.8 4903.3 4989.2 5026.3 5103.3 4871.4 4990.3 5009.1 5015.7 5064.8 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#4] prototype.html Cycles:180 Average:4398.35 Median:4914.00 stddev:1263.93 (25.7%) stddev-sans-first:1229.58 08:09:26 INFO - PROCESS | 7489 | Values: 306.0 317.4 320.5 325.1 329.2 4964.8 5142.2 5174.4 5213.0 5214.7 4993.8 5072.9 5144.3 5161.4 5162.3 4118.6 4120.9 4122.5 4129.5 4133.3 3845.5 4050.5 4136.1 4138.3 4170.5 4019.4 4024.7 4054.1 4058.4 4064.8 3933.9 3969.0 3993.1 3994.4 4046.2 3466.5 3534.1 3565.7 3580.7 3596.2 5033.7 5034.6 5044.0 5051.6 5068.3 4646.6 4652.3 4677.5 4680.9 4681.1 5300.9 5343.4 5371.8 5400.3 5447.4 3876.1 3881.7 3886.0 3888.7 3906.1 5605.6 5626.9 5636.9 5647.8 5696.2 3924.9 4012.6 4013.3 4019.1 4021.9 4039.7 4049.9 4075.0 4079.3 4082.4 4581.2 4611.6 4632.7 4668.7 4679.7 5241.8 5433.5 5444.7 5457.3 5470.9 1373.9 1388.0 1394.9 1396.5 1399.8 865.5 883.8 890.7 895.6 902.6 4568.9 4584.7 4625.4 4678.8 4692.9 4848.7 4852.3 4878.8 4886.4 4952.8 4403.7 4773.5 4775.5 4787.0 4788.8 2581.1 2594.2 2602.9 2609.0 2620.2 5028.2 5049.9 5056.8 5057.9 5077.2 4816.7 4974.0 4996.6 5016.7 5034.7 5012.8 5034.6 5055.3 5063.9 5081.2 4886.4 4897.4 4906.9 4931.4 4940.9 4596.1 4830.0 4897.2 4921.1 4949.2 5047.8 5051.0 5062.6 5083.6 5088.6 3620.7 3778.5 3859.7 3898.8 3949.9 5527.4 5535.4 5538.9 5539.0 5551.3 5331.7 5400.2 5423.9 5429.5 5460.4 5214.5 5339.9 5344.1 5365.5 5385.1 5337.8 5354.8 5376.8 5377.3 5377.4 5180.7 5416.5 5437.0 5461.1 5475.0 5399.5 5427.2 5434.6 5435.6 5437.2 08:09:26 INFO - PROCESS | 7489 | 08:09:26 INFO - PROCESS | 7489 | [#5] yui.html Cycles:180 Average:18292.07 Median:27088.91 stddev:24574.70 (90.7%) stddev-sans-first:24608.20 08:09:26 INFO - PROCESS | 7489 | Values: 716.4 728.6 763.7 787.7 792.4 14788.1 15390.0 16115.6 16137.3 16160.8 14100.4 14171.7 14185.9 14210.4 14222.1 802.3 819.3 820.4 822.1 822.9 675.3 688.0 798.6 816.9 824.0 859.1 864.4 865.8 866.1 866.2 840.2 846.7 846.9 847.1 847.5 508.2 523.2 524.6 524.7 613.1 14407.6 14876.2 14886.6 14905.5 14908.6 13397.0 13473.5 13659.3 13955.1 14064.7 12223.8 12625.0 12651.2 12687.4 13120.9 4199.5 4225.0 4258.7 4268.2 4431.8 62267.7 62288.4 62712.7 72218.6 79847.6 10749.5 10869.9 10907.6 10960.3 11005.1 19196.9 19202.7 19209.8 19224.5 19235.7 8052.8 8420.5 8421.9 8597.1 8607.9 4671.5 4742.0 4910.5 5060.8 5072.7 3209.8 3303.4 3316.6 3317.1 3317.6 573.6 583.2 583.8 594.8 595.4 603.8 616.9 654.1 661.9 690.9 8040.2 8135.9 8245.1 8279.3 8334.7 634.7 636.2 651.3 659.4 687.4 3818.3 3897.7 3923.4 4020.8 4032.4 68735.3 68938.7 70220.3 91570.0 95710.0 57021.0 57094.7 57631.0 89799.6 93108.0 77453.0 80154.0 80592.0 82436.2 82943.0 8233.3 8436.1 8567.3 8672.7 8791.8 47435.5 47895.0 48148.8 49117.0 51231.0 71305.0 74091.6 74119.3 74132.6 77196.0 10932.1 12265.9 12326.9 12331.9 13497.1 13956.9 13962.2 14387.1 14593.6 14891.9 12178.7 12597.3 12619.3 12646.7 12780.5 13082.9 13280.0 13525.9 13531.8 13702.9 12787.4 13517.0 13580.4 13766.4 13964.1 10691.5 11029.8 11109.9 11228.5 11250.4 9521.3 10605.8 10922.2 10946.6 11075.4 08:09:26 INFO - PROCESS | 7489 | -------- Summary: end -------- 08:09:26 INFO - PROCESS | 7489 | 08:09:27 INFO - TEST-INFO | 7489: exit 0 08:09:27 INFO - TEST-OK | dromaeo_css | took 1116205ms 08:09:27 INFO - TEST-START | kraken 08:09:27 INFO - operating with platform_type : linux_ 08:09:27 INFO - Initialising browser for kraken test... 08:09:27 INFO - TEST-INFO | started process 7795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSHic37/profile http://localhost:56747/getInfo.html) 08:09:31 INFO - PROCESS | 7795 | __metrics Screen width/height:1600/1200 08:09:31 INFO - PROCESS | 7795 | colorDepth:24 08:09:31 INFO - PROCESS | 7795 | Browser inner width/height: 1024/697 08:09:31 INFO - PROCESS | 7795 | __metrics 08:09:32 INFO - PROCESS | 7795 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:09:32 INFO - PROCESS | 7795 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:09:32 INFO - PROCESS | 7795 | console.error: PushService: 08:09:32 INFO - PROCESS | 7795 | stateChangeProcessEnqueue: Error transitioning state 08:09:32 INFO - PROCESS | 7795 | UnknownError 08:09:33 INFO - TEST-INFO | 7795: exit 0 08:09:33 INFO - Browser initialized. 08:09:33 INFO - Running cycle 1/1 for kraken test... 08:09:33 INFO - TEST-INFO | started process 7878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSHic37/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:45 INFO - PROCESS | 7878 | RSS: Main: 173330432 08:09:45 INFO - PROCESS | 7878 | 08:09:48 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56747/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:49 INFO - PROCESS | 7878 | RSS: Main: 240861184 08:09:49 INFO - PROCESS | 7878 | 08:09:51 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56747/tests/kraken/driver.html?testName=audio-dft) 08:09:52 INFO - PROCESS | 7878 | RSS: Main: 217341952 08:09:52 INFO - PROCESS | 7878 | 08:09:55 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56747/tests/kraken/driver.html?testName=audio-fft) 08:09:56 INFO - PROCESS | 7878 | RSS: Main: 298766336 08:09:56 INFO - PROCESS | 7878 | 08:09:58 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56747/tests/kraken/driver.html?testName=audio-oscillator) 08:09:58 INFO - PROCESS | 7878 | RSS: Main: 208760832 08:09:58 INFO - PROCESS | 7878 | 08:10:00 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:10:01 INFO - PROCESS | 7878 | RSS: Main: 211972096 08:10:01 INFO - PROCESS | 7878 | 08:10:04 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56747/tests/kraken/driver.html?testName=imaging-darkroom) 08:10:05 INFO - PROCESS | 7878 | RSS: Main: 248508416 08:10:05 INFO - PROCESS | 7878 | 08:10:08 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56747/tests/kraken/driver.html?testName=imaging-desaturate) 08:10:08 INFO - PROCESS | 7878 | RSS: Main: 247918592 08:10:09 INFO - PROCESS | 7878 | 08:10:11 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56747/tests/kraken/driver.html?testName=json-parse-financial) 08:10:12 INFO - PROCESS | 7878 | RSS: Main: 247930880 08:10:12 INFO - PROCESS | 7878 | 08:10:13 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:10:14 INFO - PROCESS | 7878 | RSS: Main: 210382848 08:10:14 INFO - PROCESS | 7878 | 08:10:16 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:10:16 INFO - PROCESS | 7878 | RSS: Main: 217837568 08:10:16 INFO - PROCESS | 7878 | 08:10:18 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:10:19 INFO - PROCESS | 7878 | RSS: Main: 222654464 08:10:19 INFO - PROCESS | 7878 | 08:10:22 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:10:22 INFO - PROCESS | 7878 | RSS: Main: 223096832 08:10:22 INFO - PROCESS | 7878 | 08:10:25 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:10:26 INFO - PROCESS | 7878 | RSS: Main: 221544448 08:10:26 INFO - PROCESS | 7878 | 08:10:27 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:56747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56747/tests/kraken/driver.html?testName=ai-astar) 08:10:28 INFO - PROCESS | 7878 | RSS: Main: 271593472 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | __start_tp_report 08:10:28 INFO - PROCESS | 7878 | _x_x_mozilla_page_load 08:10:28 INFO - PROCESS | 7878 | _x_x_mozilla_page_load_details 08:10:28 INFO - PROCESS | 7878 | |i|pagename|runs| 08:10:28 INFO - PROCESS | 7878 | |0;ai-astar;109;109;110;110;111;109;110;110;111;110 08:10:28 INFO - PROCESS | 7878 | |1;audio-beat-detection;146;118;118;160;121;119;122;123;159;119 08:10:28 INFO - PROCESS | 7878 | |2;audio-dft;162;160;160;162;160;161;162;168;160;162 08:10:28 INFO - PROCESS | 7878 | |3;audio-fft;74;75;75;118;76;79;82;75;103;76 08:10:28 INFO - PROCESS | 7878 | |4;audio-oscillator;83;81;126;82;82;82;102;81;81;118 08:10:28 INFO - PROCESS | 7878 | |5;imaging-gaussian-blur;118;117;117;116;117;117;118;118;118;118 08:10:28 INFO - PROCESS | 7878 | |6;imaging-darkroom;146;146;146;146;146;146;145;146;146;146 08:10:28 INFO - PROCESS | 7878 | |7;imaging-desaturate;96;95;97;97;95;95;95;95;96;94 08:10:28 INFO - PROCESS | 7878 | |8;json-parse-financial;52;57;51;50;51;52;62;51;52;52 08:10:28 INFO - PROCESS | 7878 | |9;json-stringify-tinderbox;60;60;60;60;61;61;60;60;60;61 08:10:28 INFO - PROCESS | 7878 | |10;stanford-crypto-aes;85;78;79;85;79;80;79;79;79;79 08:10:28 INFO - PROCESS | 7878 | |11;stanford-crypto-ccm;135;124;123;124;123;123;127;133;163;134 08:10:28 INFO - PROCESS | 7878 | |12;stanford-crypto-pbkdf2;168;169;166;169;164;167;164;171;166;166 08:10:28 INFO - PROCESS | 7878 | |13;stanford-crypto-sha256-iterative;66;66;64;65;66;65;65;64;65;64 08:10:28 INFO - PROCESS | 7878 | __end_tp_report 08:10:28 INFO - PROCESS | 7878 | __start_cc_report 08:10:28 INFO - PROCESS | 7878 | _x_x_mozilla_cycle_collect,1657 08:10:28 INFO - PROCESS | 7878 | __end_cc_report 08:10:28 INFO - PROCESS | 7878 | __startTimestamp1474125028034__endTimestamp 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | ------- Summary: start ------- 08:10:28 INFO - PROCESS | 7878 | Number of tests: 14 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 08:10:28 INFO - PROCESS | 7878 | Values: 109.0 109.0 110.0 110.0 111.0 109.0 110.0 110.0 111.0 110.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:122.50 stddev:17.38 (14.2%) stddev-sans-first:17.51 08:10:28 INFO - PROCESS | 7878 | Values: 146.0 118.0 118.0 160.0 121.0 119.0 122.0 123.0 159.0 119.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 08:10:28 INFO - PROCESS | 7878 | Values: 162.0 160.0 160.0 162.0 160.0 161.0 162.0 168.0 160.0 162.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#3] audio-fft Cycles:10 Average:83.30 Median:75.50 stddev:14.95 (19.8%) stddev-sans-first:15.48 08:10:28 INFO - PROCESS | 7878 | Values: 74.0 75.0 75.0 118.0 76.0 79.0 82.0 75.0 103.0 76.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:81.50 stddev:17.24 (21.2%) stddev-sans-first:17.99 08:10:28 INFO - PROCESS | 7878 | Values: 83.0 81.0 126.0 82.0 82.0 82.0 102.0 81.0 81.0 118.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 08:10:28 INFO - PROCESS | 7878 | Values: 118.0 117.0 117.0 116.0 117.0 117.0 118.0 118.0 118.0 118.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 08:10:28 INFO - PROCESS | 7878 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:1.01 08:10:28 INFO - PROCESS | 7878 | Values: 96.0 95.0 97.0 97.0 95.0 95.0 95.0 95.0 96.0 94.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:52.00 stddev:3.68 (7.1%) stddev-sans-first:3.89 08:10:28 INFO - PROCESS | 7878 | Values: 52.0 57.0 51.0 50.0 51.0 52.0 62.0 51.0 52.0 52.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 08:10:28 INFO - PROCESS | 7878 | Values: 60.0 60.0 60.0 60.0 61.0 61.0 60.0 60.0 60.0 61.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.00 stddev:2.57 (3.3%) stddev-sans-first:2.06 08:10:28 INFO - PROCESS | 7878 | Values: 85.0 78.0 79.0 85.0 79.0 80.0 79.0 79.0 79.0 79.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:130.00 stddev:12.29 (9.5%) stddev-sans-first:12.94 08:10:28 INFO - PROCESS | 7878 | Values: 135.0 124.0 123.0 124.0 123.0 123.0 127.0 133.0 163.0 134.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.50 stddev:2.26 (1.3%) stddev-sans-first:2.37 08:10:28 INFO - PROCESS | 7878 | Values: 168.0 169.0 166.0 169.0 164.0 167.0 164.0 171.0 166.0 166.0 08:10:28 INFO - PROCESS | 7878 | 08:10:28 INFO - PROCESS | 7878 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 08:10:28 INFO - PROCESS | 7878 | Values: 66.0 66.0 64.0 65.0 66.0 65.0 65.0 64.0 65.0 64.0 08:10:28 INFO - PROCESS | 7878 | -------- Summary: end -------- 08:10:28 INFO - PROCESS | 7878 | 08:10:29 INFO - TEST-INFO | 7878: exit 0 08:10:29 INFO - TEST-OK | kraken | took 62100ms 08:10:29 INFO - SUITE-END | took 1178s 08:10:29 INFO - Completed test suite (00:19:38) 08:10:29 INFO - javascript benchmark 08:10:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.576839242506, 3050.984745076275, 3055.3889222155576, 3083.121310426529, 3113.190570451682, 5054.494550544953, 5147.22791581263, 5175.896482070361, 5306.761195746191, 5337.119375303075, 5212.687238765677, 5233.345831771028, 5268.420473747894, 5275.340582427187, 5282.313299271099, 4731.597814185799, 4811.831585894494, 4815.398075240595, 4823.445303790074, 4906.803727850882, 4900.950990490103, 4920.532549407801, 4950.727709975949, 4966.478519755427, 4988.326575912241, 4720.669553131283, 4747.0, 4750.45369782476, 4764.023375208074, 4930.482299358569, 4775.474697783241, 4798.232282834764, 4860.659753817235, 4874.780634871439, 4876.975615121902, 4723.76381180943, 4726.929096063562, 4757.9286310705365, 4767.118083154593, 4814.06125805468, 5209.453007434203, 5211.6941469883905, 5212.791488340462, 5212.973935130338, 5282.154855223146, 5396.298481197419, 5456.208849716769, 5567.498925096761, 5630.605857589971, 5631.746571404296, 5683.8010669626365, 5683.857903552444, 5688.658680479184, 5693.089105743061, 5744.425557444264, 5281.445448227919, 5291.28567643367, 5291.523762861322, 5292.947070529322, 5357.892842143136, 5568.693721845338, 5574.637648552831, 5587.0, 5610.551155907518, 5737.627054241544, 5567.0, 5602.299712535933, 5606.747696353674, 5652.0, 5668.234788303609, 5693.544516438675, 5693.68684722336, 5695.145728140729, 5696.51579615729, 5702.686352250583, 5363.383210930715, 5420.91868621971, 5557.694326812064, 5667.574931880125, 5670.914936275959, 5616.466435688603, 5662.858428539237, 5670.5463562914865, 5736.971315143398, 5737.426257374229, 4399.912001759947, 4402.317640765622, 4408.206522825858, 4451.821927122944, 4457.153140903218, 3850.942235866464, 3857.7685338879196, 3906.882793516199, 3925.1953349563273, 3977.7215594908657, 5327.760250788724, 5365.08793505105, 5374.1938709193155, 5382.596305277119, 5402.864928376822, 5693.202951586781, 5695.145728140812, 5700.686462244616, 5704.8859022819315, 5721.0, 5153.716545589954, 5340.305760251143, 5397.865053373697, 5404.972975135178, 5460.372057213472, 4799.016201678647, 4895.7062576245535, 4907.803687852517, 4938.061768264019, 4981.501849815062, 5873.471387575053, 5887.293524777054, 5888.970555147197, 5889.440503152194, 5900.0, 5692.0608099663095, 5701.486866182064, 5704.401037890962, 5705.429457054262, 5706.771729130788, 5603.775848966159, 5606.943930560642, 5745.770169193185, 5748.0, 5793.623414478046, 5506.752196151103, 5560.388357280615, 5564.165375193753, 5564.415736347625, 5625.381208067028, 5642.915356269741, 5650.802221922213, 5653.462921022497, 5655.0, 5655.377908429987, 5243.89512209746, 5285.8678533035445, 5440.918386224131, 5462.754176062007, 5495.6153069284765, 5481.725913704219, 5509.0, 5511.559075274049, 5525.972370138124, 5547.41752116022, 5410.675359478444, 5423.484768946943, 5426.484483974016, 5427.4301198373605, 5432.809851655173, 5247.317848679647, 5289.947100528945, 5488.451154884479, 5502.422245664147, 5512.310961129859, 5401.0, 5449.155380915964, 5456.890862182813, 5498.120300751861, 5517.779288828402], "value": 5202.850932453134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.9317281926169, 619.8605313804393, 633.7750098714953, 640.7821340744148, 662.443547420167, 34965.47551786722, 42439.78780106099, 42842.0, 42868.57131428685, 46418.303725444064, 44229.115417691624, 44605.7769711151, 44642.10715785682, 44676.3298550522, 44801.1039779205, 8836.204741573258, 8979.371443998923, 8984.281257499417, 8987.820243595124, 8988.370814043019, 8764.649414023432, 8851.822963540726, 8853.84899963004, 8855.33584981129, 8858.86711699325, 8931.062238464965, 9000.14498622633, 9002.0, 9002.684906028258, 9003.324750643695, 8901.109889011112, 8902.109789021111, 8923.196912277926, 8942.597583108776, 8946.0, 8722.86915696265, 8751.649934002633, 8757.51833649149, 8762.780930476725, 8766.824663506693, 41762.0, 42086.57913420888, 42110.78944605288, 42156.578434215575, 42170.0, 3459.619441861418, 3488.5639295088113, 3611.4221724524214, 3637.3270944875285, 3768.0956570423177, 31244.375112497622, 31362.843185783924, 31677.36645267105, 31870.840645796856, 32313.1921701957, 3910.8826735197704, 3952.288588054149, 3969.2260009298197, 3979.801009949491, 4078.1435898461064, 348644.0, 350259.0, 350970.0, 351041.0, 351536.0, 56625.43374566284, 57018.7149064252, 57023.42976570181, 57042.7147864258, 57046.71476642673, 52689.0, 53991.0, 59254.40745592489, 60037.0, 60097.39902600918, 16338.428155014517, 17275.0, 17279.481615551555, 17292.0, 17301.394451194148, 12140.939295303468, 12160.878391215974, 12173.756524869454, 12195.512179512898, 12201.0, 15226.314815833313, 15733.606659833596, 16648.0, 17082.316707331815, 17253.15322971721, 6633.601983880886, 6860.759873404407, 6900.791132123587, 6908.274631163756, 6977.592730508068, 938.5776400619749, 948.7248697877676, 1020.2144348851343, 1171.6602185366319, 1178.6169494914186, 29952.0, 31226.0, 31264.843675781474, 31445.528317075263, 31705.20736981594, 1100.4387762241315, 1103.779244151173, 1129.7119234595125, 1177.0995188680665, 1196.6350263169816, 16684.74972875408, 16754.497365079067, 16788.748168777478, 16918.238679259463, 16923.20767073419, 503251.0, 507990.0, 508421.0, 508533.0, 509846.0, 56030.71984640132, 56281.0, 56399.15401268984, 56427.71786141125, 56428.717856410454, 1335.1721932401972, 1376.2224343246035, 1389.5275606293908, 1393.101449565025, 1395.413926151019, 29663.555046673886, 30833.537496937115, 31182.844085779427, 31285.0, 31442.842785785924, 50699.239511408094, 51402.74298628483, 51419.0, 51425.48574514207, 51429.48570514247, 318803.0, 319490.0, 319913.0, 320152.0, 320279.0, 33532.34775396872, 34577.48133777945, 35944.460833087, 41006.79496602498, 41466.37800432936, 40602.79698601488, 45431.0, 45874.77062614666, 46126.30810537912, 46484.30273545832, 39102.6089739099, 39220.80389598034, 39576.87616229106, 39812.40281395723, 40782.388264176654, 39370.0, 39888.401673975495, 40014.0, 40139.598604014755, 41466.792666036476, 38585.0, 39325.41011884881, 39424.40863387109, 39718.0, 40144.59855401408, 33890.0, 35532.46701299431, 36364.81817590895, 36457.0, 36545.45181822328, 27381.72618273792, 27413.314667132683, 27556.311092222855, 27557.44885102327, 27597.172084837486], "value": 19741.73608564883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.9648533389327, 380.24900820878753, 394.9150932549502, 397.3562828218287, 417.60953508469584, 14393.352299146538, 19016.33442829503, 19170.61658766824, 20576.588468230628, 20631.896840515776, 19002.61994760104, 19056.142473588687, 19602.31391901284, 19790.406287811387, 19795.0, 5311.256424100619, 5502.58730595205, 5511.779528818843, 5511.807086751965, 5513.8345849624575, 5248.422673505911, 5350.91973620394, 5385.946140538584, 5386.73066346683, 5429.918551221735, 5332.493413125766, 5394.298741163643, 5400.081986062379, 5402.864928376783, 5404.189371594272, 5176.0, 5338.67967921924, 5343.919841202365, 5370.865728356784, 5376.193570964348, 5225.320708307896, 5226.947730522684, 5235.057689615867, 5236.162214045772, 5236.73816309183, 14562.126272423582, 18791.436256912315, 19072.427827165207, 23127.53744925109, 23304.989427273216, 2174.6550777404705, 2185.0777853752393, 2300.378897697629, 2397.5204959008092, 2574.7167811540717, 10241.436720980342, 10359.378437293786, 10406.115480184193, 10817.188710846718, 10895.564177432892, 1910.4650697804636, 1922.356010736405, 1924.6728056230475, 1933.4103098554936, 1944.36808037387, 78559.60760074192, 82255.58872205601, 83407.5829620854, 84057.0, 86553.0, 11219.400045981109, 11294.830577541343, 11395.829062564067, 11543.711407214887, 11546.940060883873, 16111.27499262536, 16128.75806862898, 16132.838671613134, 16137.919310403373, 16139.757903631455, 7981.321587665114, 8121.75634730959, 8183.181681831769, 8270.503769773833, 8346.207110324509, 7573.734919277909, 7656.73201437947, 7838.608069596611, 7874.645640946172, 7875.881861772078, 4735.857924262277, 4787.042591481648, 4867.878303042452, 4888.706677599355, 4999.125153098266, 2021.9292324768858, 2035.369035598969, 2074.0209196055466, 2078.5946740385784, 2089.0, 885.6767279942775, 961.7932144588807, 974.6247694637616, 1017.1201910347496, 1019.5208252121491, 18981.0, 19592.21631134767, 19778.802211977985, 19836.50408739793, 20209.898950505154, 985.4383001689112, 1003.508280942333, 1021.213665477578, 1047.0158051431629, 1064.8189807732704, 10314.48427578618, 10818.02637762605, 10823.783524329468, 10847.977837679042, 11031.889681103086, 437394.0, 444093.0, 450408.0, 452000.0, 456948.0, 89044.5547772257, 89118.0, 89786.0, 90071.54964225137, 90238.0, 985.2856678907877, 1014.6398028699705, 1022.6676330192718, 1035.4460363705382, 1039.734867608755, 16558.58603534873, 17049.744253836452, 17399.304027838745, 17400.47798566045, 17408.129593520527, 38987.17724398162, 40316.79841600773, 42644.786776067165, 43185.7840710807, 44267.55732442763, 57345.0, 57961.71019145046, 58744.412555875606, 58871.0, 59478.405215947285, 5521.889562208814, 7790.610469476617, 8134.552599607078, 8562.058173600773, 8669.523176225115, 12234.632961011184, 12956.0, 13248.0, 13947.51183708606, 14056.859431405965, 11390.943045284721, 11403.828942566033, 11641.53433862636, 12027.939860300643, 12299.877001229874, 11253.831192532283, 11634.476448559666, 12039.0, 12053.674095849625, 12374.195677280948, 11233.71915702114, 11774.82337764917, 11831.0, 12250.387480626112, 12528.87471125277, 7777.766666999999, 8686.782830429038, 8910.86633700508, 9094.408863423856, 9132.726018219464, 10790.920907909147, 11193.160512961398, 11412.543498259976, 11512.59705910289, 11682.357470338873], "value": 9111.737534188014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.1974834847438, 1471.2423102102418, 1498.423107103765, 1625.7805196298496, 1789.1769785898493, 7817.0, 8364.790880227998, 8646.265067469258, 8666.73999780006, 8666.826663466734, 8473.87289190661, 8622.698205562807, 8641.740747777576, 8873.822523549525, 8881.200691937724, 2390.9880450597766, 2423.2245681381964, 2426.5268272686835, 2427.587310157269, 2429.6598476213344, 2359.162497313455, 2394.0902457066377, 2437.9756202438016, 2437.987810060947, 2441.5483135619966, 2311.4105902994797, 2316.4903721181313, 2318.8608683479047, 2356.057576969209, 2362.3031205794246, 2314.6296592545114, 2350.71791385034, 2358.445765245166, 2363.3737059679197, 2363.9054437822465, 2310.9653355199684, 2334.4280651240383, 2342.1685301717816, 2343.1915988983774, 2347.859128452298, 8425.199606037479, 8475.830483390358, 8483.448575842613, 8822.514761688104, 8876.822463550758, 5246.711430871301, 5468.0, 5486.149646804752, 5499.422560631157, 5503.036968530531, 3352.580927384077, 3386.72906167506, 3390.440577304742, 3406.1654894550747, 3458.5330980317585, 1080.524569189554, 1108.3571528513444, 2007.6988451732357, 2056.4961584411917, 2103.516191276015, 12088.274703517729, 12178.939105304506, 12242.204256723284, 12267.202631828814, 12275.447604857804, 4701.1537923174155, 4760.0509970706125, 4792.257200133985, 4869.318295438641, 4887.218045112765, 10463.476826158661, 10477.0, 10480.580776768997, 10482.947585262025, 10488.423136727553, 5394.838154855361, 5428.9185662215095, 5462.399136095027, 5473.808416705396, 5486.478784515464, 4268.466441694788, 4374.496932852699, 4745.928811067837, 4819.542143496362, 4967.056259310792, 4004.6996475264473, 4037.0, 4104.507459104927, 4189.601987811153, 4189.664826813909, 1320.0759468372178, 1336.3318340829585, 1338.4713038349798, 1357.4027427931678, 1357.6673714939902, 798.4211446701212, 859.2524503681836, 883.6112110671413, 898.7124120281447, 911.9042500537348, 5558.416366281563, 5824.388439213906, 5899.056151015817, 5904.704764761831, 5969.626985793292, 505.88617561049205, 662.9602223866583, 1020.7244044108049, 1022.483645758887, 1040.1159014837328, 3757.793321367351, 3883.902902427462, 3897.1231472918366, 3916.2950668879876, 3939.3893946438347, 10802.243842931077, 11145.0, 11170.776584468265, 11176.27354221973, 11178.832317515244, 11062.778744425066, 11064.114870810316, 11065.889341106486, 11099.778004439866, 11120.054795342325, 7874.606269686607, 8197.83604327922, 8205.548694821722, 8216.794580135544, 8326.875096873551, 5861.120831875252, 5903.085021821625, 6034.849128771816, 6061.484773794277, 6124.38756124384, 10160.796784064425, 10172.440515771703, 10222.182225422097, 10288.12550933179, 10318.43248621303, 10075.042870927251, 10161.339512931636, 10208.285420020527, 10247.910849645865, 10436.425996569958, 7014.403775679124, 7136.60748658808, 7229.421646268391, 7611.200823913409, 7839.372850171859, 8522.36082293818, 8858.822823543622, 8862.42394244372, 8883.111688831064, 8888.688895888872, 7380.483366164317, 7389.519681220703, 7589.9374087626675, 7599.354054905395, 7600.771976840703, 7195.8560828784175, 7384.224656410999, 7513.774586762406, 7672.654730537028, 7751.961240193763, 6736.427403670743, 6851.109355783717, 6852.657367131723, 6854.588724676535, 6863.347981941708, 4868.780904859219, 4903.289023091703, 4989.176785830297, 5026.271190677405, 5103.285540024473, 4871.3667223260745, 4990.251462280542, 5009.148444764472, 5015.724135172601, 5064.772085256246], "value": 4672.454206808275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.97552195824335, 317.43179708322106, 320.4584252613082, 325.09461150695313, 329.1901921273667, 4964.826231081913, 5142.228665700138, 5174.43081261061, 5213.0, 5214.661047031945, 4993.825216117437, 5072.9492705072935, 5144.25408315794, 5161.380634323877, 5162.328897243353, 4118.608732170454, 4120.928558574762, 4122.50529936408, 4129.504459464861, 4133.297339452301, 3845.538535375759, 4050.521559630743, 4136.110736191718, 4138.2758017347, 4170.4995400551825, 4019.3970904364432, 4024.738392004525, 4054.148628787958, 4058.3506638937774, 4064.7561146331313, 3933.8985084176334, 3968.987908083449, 3993.1414745829497, 3994.380870965005, 4046.150308435232, 3466.5146879436647, 3534.098804804784, 3565.6612621801146, 3580.7135429165605, 3596.183927116789, 5033.748312584356, 5034.647574669774, 5043.974780126113, 5051.621128415383, 5068.290439338495, 4646.605038571725, 4652.255639097762, 4677.50886156952, 4680.906381872377, 4681.133990211788, 5300.92048619271, 5343.438938911436, 5371.785128594852, 5400.280443320006, 5447.373552041487, 3876.089119057019, 3881.6894648428056, 3886.0, 3888.747231429948, 3906.1015966327905, 5605.607607467479, 5626.887462250773, 5636.915446268309, 5647.774089036475, 5696.202531645573, 3924.9215015700097, 4012.598740126022, 4013.337799263089, 4019.1358857845703, 4021.879343619696, 4039.6566291864933, 4049.919001619951, 4074.9796251018556, 4079.347304431277, 4082.3672330788777, 4581.2440947243995, 4611.5619016193405, 4632.698874573142, 4668.6965347253, 4679.695819771704, 5241.842744717665, 5433.483819037185, 5444.686008446473, 5457.317835270591, 5470.945290547043, 1373.9175649460865, 1387.9583612491642, 1394.9023568350322, 1396.525181438316, 1399.846016938136, 865.5499140447058, 883.7834730491071, 890.6708956079934, 895.6238379880466, 902.5803001604154, 4568.862934111982, 4584.701994370355, 4625.444946606362, 4678.836240731558, 4692.85921422351, 4848.684835485682, 4852.344933434012, 4878.829240976549, 4886.389201349832, 4952.801887924515, 4403.735775853459, 4773.474917759044, 4775.450823155312, 4787.018661174451, 4788.784504697297, 2581.1482210870367, 2594.171109367891, 2602.921912342633, 2609.0, 2620.2139358192467, 5028.17035189197, 5049.873753156127, 5056.84829455117, 5057.924131138036, 5077.238414237894, 4816.710997340101, 4973.975130124326, 4996.550310472075, 5016.673916195508, 5034.723090230073, 5012.8496145115705, 5034.647574669812, 5055.317532133188, 5063.924041139312, 5081.1707785197295, 4886.389201349832, 4897.363342765418, 4906.877328066684, 4931.408231012301, 4940.925886111783, 4596.115592607015, 4829.975850120727, 4897.240927656219, 4921.129970611145, 4949.183384741621, 5047.823326183566, 5050.954363482795, 5062.620303477328, 5083.644144909968, 5088.643794934319, 3620.6741393274733, 3778.4899038630533, 3859.691224702073, 3898.78556679374, 3949.861754838567, 5527.39198688152, 5535.446455354432, 5538.9169162461785, 5539.0, 5551.333839939233, 5331.693735034942, 5400.162974738922, 5423.945760542502, 5429.475293988315, 5460.426655201147, 5214.478552144755, 5339.893202136013, 5344.144936810092, 5365.517103460707, 5385.111456609616, 5337.786488540415, 5354.812581559627, 5376.784928602812, 5377.327834020747, 5377.435369286325, 5180.663256888291, 5416.51251387377, 5436.972815135899, 5461.098918678374, 5474.972625136849, 5399.514043736082, 5427.24018637399, 5434.592405569677, 5435.646682965595, 5437.184422336682], "value": 3996.4499653027788, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.4340171264698, 728.646606395898, 763.7479631721533, 787.6927998080746, 792.3898598079483, 14788.112713237213, 15390.0, 16115.630171435441, 16137.273822677964, 16160.757588636165, 14100.36548355324, 14171.716565668681, 14185.858141418583, 14210.360533775978, 14222.075565088262, 802.2619190344897, 819.3076850061689, 820.36421773126, 822.116225058065, 822.8930239068912, 675.2572170612336, 687.9793606191822, 798.648594618369, 816.9387295952828, 824.0194168938973, 859.0979471554874, 864.3560547392202, 865.8008658008662, 866.1165611076698, 866.2117473099481, 840.2437805974611, 846.7121178799207, 846.9322454203649, 847.0513025411578, 847.5338563789891, 508.1640701046794, 523.1890569617117, 524.6065450911816, 524.7009204753292, 613.1140501974648, 14407.639809004753, 14876.181810000446, 14886.627834304121, 14905.478308259268, 14908.552743417715, 13397.0, 13473.461061557526, 13659.317034148253, 13955.092918960336, 14064.71870562583, 12223.755524889453, 12625.0, 12651.177673451288, 12687.365631718378, 13120.868791312156, 4199.454070970784, 4224.957750422487, 4258.680598955059, 4268.231718290706, 4431.844885429026, 62267.6886615564, 62288.37711622871, 62712.68643656753, 72218.63890680566, 79847.6007619964, 10749.500998003992, 10869.945650271698, 10907.563697452171, 10960.342379457257, 11005.119590432747, 19196.90401548011, 19202.711959320623, 19209.80790192108, 19224.519387015436, 19235.711464328047, 8052.758417247374, 8420.53687047206, 8421.873671894926, 8597.097304782908, 8607.913920860836, 4671.509491503343, 4741.95258047422, 4910.508949105061, 5060.822871199491, 5072.67027643202, 3209.7753157278767, 3303.3888730584918, 3316.6185888623118, 3317.1209629448217, 3317.6018877734828, 573.6414740787009, 583.2243116654839, 583.7635757518127, 594.8037147741235, 595.3718826637938, 603.8097999130348, 616.8797084568466, 654.1234745441132, 661.9139511863523, 690.8825499665069, 8040.15578364275, 8135.877961830577, 8245.051819040767, 8279.296259817984, 8334.749957501284, 634.6985182038495, 636.2015670333701, 651.26407159909, 659.4131223211346, 687.3504538211442, 3818.3317919364, 3897.7271590988357, 3923.4114882767244, 4020.798960051986, 4032.3951407288555, 68735.31264687289, 68938.65530672415, 70220.2977970224, 91570.0, 95710.0, 57021.0, 57094.7145264271, 57631.0, 89799.55100224457, 93108.0, 77453.0, 80154.0, 80592.0, 82436.22238553035, 82943.0, 8233.300169485421, 8436.072032076589, 8567.27178189861, 8672.696455624024, 8791.780205494913, 47435.52564474449, 47895.0, 48148.75925620349, 49117.0, 51231.0, 71305.0, 74091.62954185194, 74119.25880741124, 74132.62933685297, 77196.0, 10932.0707739843, 12265.877341226473, 12326.938365308117, 12331.938340308241, 13497.055206135476, 13956.930215348859, 13962.232077235443, 14387.136771793726, 14593.635159120682, 14891.851081489047, 12178.695532611755, 12597.307148106576, 12619.30593817349, 12646.683832903884, 12780.488780448677, 13082.934585327394, 13280.0, 13525.926515031235, 13531.75237942867, 13702.86297137016, 12787.360631968178, 13517.0, 13580.388882500509, 13766.380512876744, 13964.092333998258, 10691.465426728788, 11029.779404411707, 11109.944450277697, 11228.494717737558, 11250.381229032155, 9521.285903557122, 10605.840912386475, 10922.18083643714, 10946.616868409568, 11075.390853503135], "value": 7110.665597581339, "unit": "score"}], "name": "dromaeo_css", "value": 7064.272239638271, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 110.0, 110.0, 111.0, 109.0, 110.0, 110.0, 111.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 118.0, 160.0, 121.0, 119.0, 122.0, 123.0, 159.0, 119.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 162.0, 160.0, 161.0, 162.0, 168.0, 160.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 118.0, 76.0, 79.0, 82.0, 75.0, 103.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 126.0, 82.0, 82.0, 82.0, 102.0, 81.0, 81.0, 118.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 97.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 57.0, 51.0, 50.0, 51.0, 52.0, 62.0, 51.0, 52.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 79.0, 85.0, 79.0, 80.0, 79.0, 79.0, 79.0, 79.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 124.0, 123.0, 124.0, 123.0, 123.0, 127.0, 133.0, 163.0, 134.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 166.0, 169.0, 164.0, 167.0, 164.0, 171.0, 166.0, 166.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 65.0, 66.0, 65.0, 65.0, 64.0, 65.0, 64.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1492.4, "alertThreshold": 2.0}]} 08:10:29 INFO - Return code: 0 08:10:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:10:29 INFO - # TBPL SUCCESS # 08:10:29 INFO - Running post-action listener: _resource_record_post_action 08:10:29 INFO - [mozharness: 2016-09-17 15:10:29.856941Z] Finished run-tests step (success) 08:10:29 INFO - Running post-run listener: _resource_record_post_run 08:10:29 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406863872; Read time: 16; Write time: 308720 08:10:29 INFO - TinderboxPrint: CPU usage
13.2% 08:10:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:10:29 INFO - TinderboxPrint: I/O write bytes / time
406,863,872 / 308,720 08:10:29 INFO - TinderboxPrint: CPU idle
8,286.4 (86.8%) 08:10:29 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 08:10:29 INFO - TinderboxPrint: Swap in / out
0 / 0 08:10:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110915584; Read time: 0; Write time: 109084 08:10:29 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 295944192; Read time: 0; Write time: 199636 08:10:30 INFO - Running post-run listener: _upload_blobber_files 08:10:30 INFO - Blob upload gear active. 08:10:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:10:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:10:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:10:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:10:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:10:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:10:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:31 INFO - (blobuploader) - INFO - Done attempting. 08:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:10:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:38 INFO - (blobuploader) - INFO - Done attempting. 08:10:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:10:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:10:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:39 INFO - (blobuploader) - INFO - Done attempting. 08:10:39 INFO - (blobuploader) - INFO - Iteration through files over. 08:10:39 INFO - Return code: 0 08:10:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:10:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:10:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c449f0ee8ce1144a16932643277d0f03f239092f4163986b0c8ef17dcc1c1df23a5b35dca1551cba9cba15956bd018178b72fccafff23fdc6da762c263f1209d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f88032bccc3e654d39d82e1d64db3d3b2d1a4329098a7838f76f34adec250f10c47eb2514d4985494eace5d2fce17ff403c222ddc7dec15026a25b06bba5a7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661220b2e789ac4a6cb7f787129331d47fdee913701cc95d6352d997b6f14569a20c50372d28dfcd46913cc5e4143b477fe1286a72eb98464f9607ead8f5b6e0"} 08:10:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:10:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:10:39 INFO - Contents: 08:10:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c449f0ee8ce1144a16932643277d0f03f239092f4163986b0c8ef17dcc1c1df23a5b35dca1551cba9cba15956bd018178b72fccafff23fdc6da762c263f1209d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f88032bccc3e654d39d82e1d64db3d3b2d1a4329098a7838f76f34adec250f10c47eb2514d4985494eace5d2fce17ff403c222ddc7dec15026a25b06bba5a7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661220b2e789ac4a6cb7f787129331d47fdee913701cc95d6352d997b6f14569a20c50372d28dfcd46913cc5e4143b477fe1286a72eb98464f9607ead8f5b6e0"} 08:10:39 INFO - Running post-run listener: copy_logs_to_upload_dir 08:10:39 INFO - Copying logs to upload dir... 08:10:39 INFO - mkdir: /builds/slave/test/build/upload/logs 08:10:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.810055 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 08:10:39.007295) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:39.013171) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 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/c449f0ee8ce1144a16932643277d0f03f239092f4163986b0c8ef17dcc1c1df23a5b35dca1551cba9cba15956bd018178b72fccafff23fdc6da762c263f1209d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f88032bccc3e654d39d82e1d64db3d3b2d1a4329098a7838f76f34adec250f10c47eb2514d4985494eace5d2fce17ff403c222ddc7dec15026a25b06bba5a7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661220b2e789ac4a6cb7f787129331d47fdee913701cc95d6352d997b6f14569a20c50372d28dfcd46913cc5e4143b477fe1286a72eb98464f9607ead8f5b6e0"} build_url:https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011469 build_url: 'https://queue.taskcluster.net/v1/task/O14MNDmsRw6EFcLkms9dag/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/c449f0ee8ce1144a16932643277d0f03f239092f4163986b0c8ef17dcc1c1df23a5b35dca1551cba9cba15956bd018178b72fccafff23fdc6da762c263f1209d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f88032bccc3e654d39d82e1d64db3d3b2d1a4329098a7838f76f34adec250f10c47eb2514d4985494eace5d2fce17ff403c222ddc7dec15026a25b06bba5a7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661220b2e789ac4a6cb7f787129331d47fdee913701cc95d6352d997b6f14569a20c50372d28dfcd46913cc5e4143b477fe1286a72eb98464f9607ead8f5b6e0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:39.038728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:39.039065) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1474123794.745614-323782376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:10:39.122911) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:10:39.123263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:10:39.123590) ========= ========= Total master_lag: 0.17 =========