builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-010 starttime: 1487336064.73 results: success (0) revision: 03fc0a686d3f8e0c38136771579d030c7a8c0b82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.731707) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.732210) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.732485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.745555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.745894) ========= 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=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 04:54:25-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 96.9M=0s 2017-02-17 04:54:25 (96.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084020 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.844151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.844471) ========= 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=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018182 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.896503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:24.897093) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03fc0a686d3f8e0c38136771579d030c7a8c0b82 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 03fc0a686d3f8e0c38136771579d030c7a8c0b82 --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=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 04:54:25,583 truncating revision to first 12 chars 2017-02-17 04:54:25,583 Setting DEBUG logging. 2017-02-17 04:54:25,583 attempt 1/10 2017-02-17 04:54:25,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03fc0a686d3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 04:54:26,131 unpacking tar archive at: mozilla-central-03fc0a686d3f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.853172 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:25.763579) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:25.763903) ========= script_repo_revision: 03fc0a686d3f8e0c38136771579d030c7a8c0b82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:25.767395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:25.767683) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 04:54:25.777445) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-17 04:54:25.777849) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:54:26 INFO - MultiFileLogger online at 20170217 04:54:26 in /builds/slave/test 04:54:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:54:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:54:26 INFO - {'append_to_log': False, 04:54:26 INFO - 'base_work_dir': '/builds/slave/test', 04:54:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:54:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:54:26 INFO - 'branch': 'Firefox-Non-PGO', 04:54:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:54:26 INFO - 'config_files': ('talos/linux_config.py',), 04:54:26 INFO - 'default_actions': ('clobber', 04:54:26 INFO - 'read-buildbot-config', 04:54:26 INFO - 'download-and-extract', 04:54:26 INFO - 'populate-webroot', 04:54:26 INFO - 'create-virtualenv', 04:54:26 INFO - 'install', 04:54:26 INFO - 'run-tests'), 04:54:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:54:26 INFO - 'download_minidump_stackwalk': True, 04:54:26 INFO - 'download_symbols': 'ondemand', 04:54:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:54:26 INFO - 'tooltool.py': '/tools/tooltool.py', 04:54:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:54:26 INFO - '/tools/misc-python/virtualenv.py')}, 04:54:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:54:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:54:26 INFO - 'installer_path': 'installer.exe', 04:54:26 INFO - 'log_level': 'info', 04:54:26 INFO - 'log_name': 'talos', 04:54:26 INFO - 'log_to_console': True, 04:54:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:54:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:54:26 INFO - 'opt_config_files': (), 04:54:26 INFO - 'pip_index': False, 04:54:26 INFO - 'sps_profile': False, 04:54:26 INFO - 'sps_profile_interval': 0, 04:54:26 INFO - 'suite': 'dromaeojs', 04:54:26 INFO - 'system_bits': '32', 04:54:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:54:26 INFO - 'title': 'talos-linux64-ix-010', 04:54:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:54:26 INFO - 'use_talos_json': True, 04:54:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:54:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:54:26 INFO - 'work_dir': 'build'} 04:54:26 INFO - [mozharness: 2017-02-17 12:54:26.563370Z] Running clobber step. 04:54:26 INFO - Running pre-action listener: _resource_record_pre_action 04:54:26 INFO - Running main action method: clobber 04:54:26 INFO - rmtree: /builds/slave/test/build 04:54:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:54:27 INFO - Running post-action listener: _resource_record_post_action 04:54:27 INFO - [mozharness: 2017-02-17 12:54:27.496308Z] Finished clobber step (success) 04:54:27 INFO - [mozharness: 2017-02-17 12:54:27.496414Z] Running read-buildbot-config step. 04:54:27 INFO - Running pre-action listener: _resource_record_pre_action 04:54:27 INFO - Running main action method: read_buildbot_config 04:54:27 INFO - Using buildbot properties: 04:54:27 INFO - { 04:54:27 INFO - "project": "", 04:54:27 INFO - "product": "firefox", 04:54:27 INFO - "who": "cbook@mozilla.com", 04:54:27 INFO - "installer_path": "public/build/target.tar.bz2", 04:54:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:54:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:54:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 04:54:27 INFO - "stage_platform": "linux64", 04:54:27 INFO - "basedir": "/builds/slave/test", 04:54:27 INFO - "buildnumber": 833, 04:54:27 INFO - "platform": "ubuntu64_hw", 04:54:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:54:27 INFO - "slavebuilddir": "test", 04:54:27 INFO - "taskId": "KxAprsAKTfGpqCbCgu8GCw", 04:54:27 INFO - "branch": "mozilla-central", 04:54:27 INFO - "script_repo_revision": "production", 04:54:27 INFO - "revision": "03fc0a686d3f8e0c38136771579d030c7a8c0b82", 04:54:27 INFO - "slavename": "talos-linux64-ix-010", 04:54:27 INFO - "repo_path": "mozilla-central" 04:54:27 INFO - } 04:54:27 INFO - Finding installer, test and symbols from parent task. 04:54:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KxAprsAKTfGpqCbCgu8GCw'}, attempt #1 04:54:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KxAprsAKTfGpqCbCgu8GCw'}, attempt #1 04:54:28 INFO - Task dependencies: eKk5bPybTdCK3v1cu3R2Uw 04:54:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw'}, attempt #1 04:54:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 04:54:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/test_packages.json 04:54:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.crashreporter-symbols.zip 04:54:28 INFO - Running post-action listener: _resource_record_post_action 04:54:28 INFO - [mozharness: 2017-02-17 12:54:28.875220Z] Finished read-buildbot-config step (success) 04:54:28 INFO - [mozharness: 2017-02-17 12:54:28.875440Z] Running download-and-extract step. 04:54:28 INFO - Running pre-action listener: _resource_record_pre_action 04:54:28 INFO - Running main action method: download_and_extract 04:54:28 INFO - mkdir: /builds/slave/test/build/tests 04:54:28 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')]} 04:54:28 INFO - trying https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.test_packages.json 04:54:28 INFO - Downloading https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:54:29 INFO - Downloaded 896 bytes. 04:54:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:54:29 INFO - Using the following test package requirements: 04:54:29 INFO - {u'common': [u'target.common.tests.zip'], 04:54:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:54:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:54:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:54:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:54:29 INFO - u'mozbase': [u'target.common.tests.zip'], 04:54:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:54:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:54:29 INFO - u'web-platform': [u'target.common.tests.zip', 04:54:29 INFO - u'target.web-platform.tests.zip'], 04:54:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:54:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:54:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip 04:54:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:54:29 INFO - Fetch https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip into memory 04:54:30 INFO - Expected file size: 57027602 04:54:30 INFO - Obtained file size: 57027602 04:54:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:54:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip 04:54:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:54:40 INFO - Fetch https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.common.tests.zip into memory 04:54:41 INFO - Expected file size: 57027602 04:54:41 INFO - Obtained file size: 57027602 04:54:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.talos.tests.zip 04:54:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:54:51 INFO - Fetch https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.talos.tests.zip into memory 04:54:52 INFO - Expected file size: 14403647 04:54:52 INFO - Obtained file size: 14403647 04:54:53 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')]} 04:54:53 INFO - trying https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 04:54:53 INFO - Downloading https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:54:54 INFO - Downloaded 61355317 bytes. 04:54:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 04:54:54 INFO - mkdir: /builds/slave/test/properties 04:54:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:54:54 INFO - Writing to file /builds/slave/test/properties/build_url 04:54:54 INFO - Contents: 04:54:54 INFO - build_url:https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 04:54:54 INFO - Running post-action listener: _resource_record_post_action 04:54:54 INFO - Running post-action listener: set_extra_try_arguments 04:54:54 INFO - [mozharness: 2017-02-17 12:54:54.145038Z] Finished download-and-extract step (success) 04:54:54 INFO - [mozharness: 2017-02-17 12:54:54.145135Z] Running populate-webroot step. 04:54:54 INFO - Running pre-action listener: _resource_record_pre_action 04:54:54 INFO - Running main action method: populate_webroot 04:54:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:54:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:54:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:54:54 INFO - '--tppagecycles', 04:54:54 INFO - '7'], 04:54:54 INFO - 'tests': ['tsvgm']}}, 04:54:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:54:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:54:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:54:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:54:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['damp', 'tps']}, 04:54:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'tests': ['damp', 'tps']}, 04:54:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['dromaeo_dom']}, 04:54:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:54:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['basic_compositor_video']}, 04:54:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:54:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['a11yr', 04:54:54 INFO - 'ts_paint', 04:54:54 INFO - 'tpaint', 04:54:54 INFO - 'sessionrestore', 04:54:54 INFO - 'sessionrestore_no_auto_restore', 04:54:54 INFO - 'tabpaint']}, 04:54:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['tsvgx', 04:54:54 INFO - 'tsvgr_opacity', 04:54:54 INFO - 'tart', 04:54:54 INFO - 'tscrollx', 04:54:54 INFO - 'cart', 04:54:54 INFO - 'tsvg_static']}, 04:54:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:54:54 INFO - 'tsvgr_opacity', 04:54:54 INFO - 'tart', 04:54:54 INFO - 'tscrollx', 04:54:54 INFO - 'cart', 04:54:54 INFO - 'tsvg_static']}, 04:54:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'talos_options': ['--disable-e10s'], 04:54:54 INFO - 'tests': ['tp5o']}, 04:54:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:54:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'talos_options': ['--disable-e10s', 04:54:54 INFO - '--xperf_path', 04:54:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:54 INFO - 'tests': ['tp5n']}, 04:54:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:54:54 INFO - 'talos_options': ['--xperf_path', 04:54:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:54 INFO - 'tests': ['tp5n']}}, 04:54:54 INFO - 'talos.zip': {'path': '', 04:54:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:54:54 INFO - Running post-action listener: _resource_record_post_action 04:54:54 INFO - [mozharness: 2017-02-17 12:54:54.158327Z] Finished populate-webroot step (success) 04:54:54 INFO - [mozharness: 2017-02-17 12:54:54.158420Z] Running create-virtualenv step. 04:54:54 INFO - Running pre-action listener: _resource_record_pre_action 04:54:54 INFO - Running main action method: create_virtualenv 04:54:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:54:54 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 04:54:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:54:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:54:54 INFO - Using real prefix '/usr' 04:54:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:54:54 INFO - Installing distribute.............................................................................................................................................................................................done. 04:54:56 INFO - Installing pip.................done. 04:54:56 INFO - Return code: 0 04:54:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:54:56 INFO - Reading from file tmpfile_stdout 04:54:56 INFO - Output received: 04:54:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:54:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:54:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:54:56 INFO - Using env: {'DISPLAY': ':0', 04:54:56 INFO - 'HOME': '/home/cltbld', 04:54:56 INFO - 'LANG': 'en_US.UTF-8', 04:54:56 INFO - 'LANGUAGE': 'en_US:en', 04:54:56 INFO - 'LOGNAME': 'cltbld', 04:54:56 INFO - 'MAIL': '/var/mail/cltbld', 04:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:56 INFO - 'MOZ_NO_REMOTE': '1', 04:54:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:54:56 INFO - 'NO_EM_RESTART': '1', 04:54:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:56 INFO - 'PWD': '/builds/slave/test', 04:54:56 INFO - 'SHELL': '/bin/bash', 04:54:56 INFO - 'SHLVL': '1', 04:54:56 INFO - 'TERM': 'linux', 04:54:56 INFO - 'TMOUT': '86400', 04:54:56 INFO - 'USER': 'cltbld', 04:54:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:56 INFO - '_': '/tools/buildbot/bin/python'} 04:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:57 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 04:54:57 INFO - Cleaning up... 04:54:57 INFO - Return code: 0 04:54:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:54:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:54:57 INFO - Using env: {'DISPLAY': ':0', 04:54:57 INFO - 'HOME': '/home/cltbld', 04:54:57 INFO - 'LANG': 'en_US.UTF-8', 04:54:57 INFO - 'LANGUAGE': 'en_US:en', 04:54:57 INFO - 'LOGNAME': 'cltbld', 04:54:57 INFO - 'MAIL': '/var/mail/cltbld', 04:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:57 INFO - 'MOZ_NO_REMOTE': '1', 04:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:54:57 INFO - 'NO_EM_RESTART': '1', 04:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:57 INFO - 'PWD': '/builds/slave/test', 04:54:57 INFO - 'SHELL': '/bin/bash', 04:54:57 INFO - 'SHLVL': '1', 04:54:57 INFO - 'TERM': 'linux', 04:54:57 INFO - 'TMOUT': '86400', 04:54:57 INFO - 'USER': 'cltbld', 04:54:57 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:57 INFO - '_': '/tools/buildbot/bin/python'} 04:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:57 INFO - Downloading/unpacking psutil>=3.1.1 04:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:54:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:59 INFO - Installing collected packages: psutil 04:54:59 INFO - Running setup.py install for psutil 04:54:59 INFO - building 'psutil._psutil_linux' extension 04:54:59 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 04:54:59 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 04:54:59 INFO - building 'psutil._psutil_posix' extension 04:54:59 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 04:54:59 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 04:54:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:00 INFO - Successfully installed psutil 04:55:00 INFO - Cleaning up... 04:55:00 INFO - Return code: 0 04:55:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:55:00 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')]} 04:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:00 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')]} 04:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:00 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:00 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 04:55:00 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 04:55:00 INFO - Using env: {'DISPLAY': ':0', 04:55:00 INFO - 'HOME': '/home/cltbld', 04:55:00 INFO - 'LANG': 'en_US.UTF-8', 04:55:00 INFO - 'LANGUAGE': 'en_US:en', 04:55:00 INFO - 'LOGNAME': 'cltbld', 04:55:00 INFO - 'MAIL': '/var/mail/cltbld', 04:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:00 INFO - 'MOZ_NO_REMOTE': '1', 04:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:00 INFO - 'NO_EM_RESTART': '1', 04:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:00 INFO - 'PWD': '/builds/slave/test', 04:55:00 INFO - 'SHELL': '/bin/bash', 04:55:00 INFO - 'SHLVL': '1', 04:55:00 INFO - 'TERM': 'linux', 04:55:00 INFO - 'TMOUT': '86400', 04:55:00 INFO - 'USER': 'cltbld', 04:55:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:00 INFO - '_': '/tools/buildbot/bin/python'} 04:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:55:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:55:02 INFO - Installing collected packages: mozsystemmonitor 04:55:02 INFO - Running setup.py install for mozsystemmonitor 04:55:02 INFO - Successfully installed mozsystemmonitor 04:55:02 INFO - Cleaning up... 04:55:02 INFO - Return code: 0 04:55:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:02 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:02 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 04:55:02 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 04:55:02 INFO - Using env: {'DISPLAY': ':0', 04:55:02 INFO - 'HOME': '/home/cltbld', 04:55:02 INFO - 'LANG': 'en_US.UTF-8', 04:55:02 INFO - 'LANGUAGE': 'en_US:en', 04:55:02 INFO - 'LOGNAME': 'cltbld', 04:55:02 INFO - 'MAIL': '/var/mail/cltbld', 04:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:02 INFO - 'MOZ_NO_REMOTE': '1', 04:55:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:02 INFO - 'NO_EM_RESTART': '1', 04:55:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:02 INFO - 'PWD': '/builds/slave/test', 04:55:02 INFO - 'SHELL': '/bin/bash', 04:55:02 INFO - 'SHLVL': '1', 04:55:02 INFO - 'TERM': 'linux', 04:55:02 INFO - 'TMOUT': '86400', 04:55:02 INFO - 'USER': 'cltbld', 04:55:02 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:02 INFO - '_': '/tools/buildbot/bin/python'} 04:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:02 INFO - Downloading/unpacking jsonschema==2.5.1 04:55:02 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 04:55:02 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 04:55:02 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 04:55:02 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 04:55:02 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 04:55:02 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 04:55:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:55:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:05 INFO - Downloading functools32-3.2.3-2.tar.gz 04:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:55:05 INFO - warning: no files found matching '*.txt' 04:55:05 INFO - no previously-included directories found matching 'build' 04:55:05 INFO - no previously-included directories found matching 'dist' 04:55:05 INFO - no previously-included directories found matching '.git*' 04:55:05 INFO - Installing collected packages: jsonschema, functools32 04:55:05 INFO - Running setup.py install for functools32 04:55:05 INFO - warning: no files found matching '*.txt' 04:55:05 INFO - no previously-included directories found matching 'build' 04:55:05 INFO - no previously-included directories found matching 'dist' 04:55:05 INFO - no previously-included directories found matching '.git*' 04:55:05 INFO - Successfully installed jsonschema functools32 04:55:05 INFO - Cleaning up... 04:55:05 INFO - Return code: 0 04:55:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:05 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 04:55:05 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 04:55:05 INFO - Using env: {'DISPLAY': ':0', 04:55:05 INFO - 'HOME': '/home/cltbld', 04:55:05 INFO - 'LANG': 'en_US.UTF-8', 04:55:05 INFO - 'LANGUAGE': 'en_US:en', 04:55:05 INFO - 'LOGNAME': 'cltbld', 04:55:05 INFO - 'MAIL': '/var/mail/cltbld', 04:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:05 INFO - 'MOZ_NO_REMOTE': '1', 04:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:05 INFO - 'NO_EM_RESTART': '1', 04:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:05 INFO - 'PWD': '/builds/slave/test', 04:55:05 INFO - 'SHELL': '/bin/bash', 04:55:05 INFO - 'SHLVL': '1', 04:55:05 INFO - 'TERM': 'linux', 04:55:05 INFO - 'TMOUT': '86400', 04:55:05 INFO - 'USER': 'cltbld', 04:55:05 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:05 INFO - '_': '/tools/buildbot/bin/python'} 04:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:55:05 INFO - Cleaning up... 04:55:05 INFO - Return code: 0 04:55:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:05 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 04:55:05 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 04:55:05 INFO - Using env: {'DISPLAY': ':0', 04:55:05 INFO - 'HOME': '/home/cltbld', 04:55:05 INFO - 'LANG': 'en_US.UTF-8', 04:55:05 INFO - 'LANGUAGE': 'en_US:en', 04:55:05 INFO - 'LOGNAME': 'cltbld', 04:55:05 INFO - 'MAIL': '/var/mail/cltbld', 04:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:05 INFO - 'MOZ_NO_REMOTE': '1', 04:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:05 INFO - 'NO_EM_RESTART': '1', 04:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:05 INFO - 'PWD': '/builds/slave/test', 04:55:05 INFO - 'SHELL': '/bin/bash', 04:55:05 INFO - 'SHLVL': '1', 04:55:05 INFO - 'TERM': 'linux', 04:55:05 INFO - 'TMOUT': '86400', 04:55:05 INFO - 'USER': 'cltbld', 04:55:05 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:05 INFO - '_': '/tools/buildbot/bin/python'} 04:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:06 INFO - Downloading/unpacking blobuploader==1.2.4 04:55:06 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 04:55:06 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 04:55:06 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 04:55:06 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 04:55:06 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 04:55:06 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 04:55:08 INFO - Downloading blobuploader-1.2.4.tar.gz 04:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:55:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:55:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 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 04:55:08 INFO - Downloading docopt-0.6.1.tar.gz 04:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:55:08 INFO - Installing collected packages: blobuploader, requests, docopt 04:55:08 INFO - Running setup.py install for blobuploader 04:55:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:55:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:55:08 INFO - Running setup.py install for requests 04:55:09 INFO - Running setup.py install for docopt 04:55:09 INFO - Successfully installed blobuploader requests docopt 04:55:09 INFO - Cleaning up... 04:55:09 INFO - Return code: 0 04:55:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:09 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:09 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 04:55:09 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 04:55:09 INFO - Using env: {'DISPLAY': ':0', 04:55:09 INFO - 'HOME': '/home/cltbld', 04:55:09 INFO - 'LANG': 'en_US.UTF-8', 04:55:09 INFO - 'LANGUAGE': 'en_US:en', 04:55:09 INFO - 'LOGNAME': 'cltbld', 04:55:09 INFO - 'MAIL': '/var/mail/cltbld', 04:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:09 INFO - 'MOZ_NO_REMOTE': '1', 04:55:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:09 INFO - 'NO_EM_RESTART': '1', 04:55:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:09 INFO - 'PWD': '/builds/slave/test', 04:55:09 INFO - 'SHELL': '/bin/bash', 04:55:09 INFO - 'SHLVL': '1', 04:55:09 INFO - 'TERM': 'linux', 04:55:09 INFO - 'TMOUT': '86400', 04:55:09 INFO - 'USER': 'cltbld', 04:55:09 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:09 INFO - '_': '/tools/buildbot/bin/python'} 04:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:09 INFO - Running setup.py (path:/tmp/pip-BGBEXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:09 INFO - Running setup.py (path:/tmp/pip-MuCmsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:09 INFO - Running setup.py (path:/tmp/pip-gg8eDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:09 INFO - Running setup.py (path:/tmp/pip-0NUerI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:09 INFO - Running setup.py (path:/tmp/pip-Gp0Khi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:10 INFO - Running setup.py (path:/tmp/pip-_ojD5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:10 INFO - Running setup.py (path:/tmp/pip-bU7s1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:10 INFO - Running setup.py (path:/tmp/pip-dhFTRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:10 INFO - Running setup.py (path:/tmp/pip-aYXBhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:10 INFO - Running setup.py (path:/tmp/pip-xCv7WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:10 INFO - Running setup.py (path:/tmp/pip-RVmcQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:10 INFO - Running setup.py (path:/tmp/pip-6iYLoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:10 INFO - Running setup.py (path:/tmp/pip-sbI_6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:10 INFO - Running setup.py (path:/tmp/pip-fWRCCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:10 INFO - Running setup.py (path:/tmp/pip-MXrNKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:10 INFO - Running setup.py (path:/tmp/pip-bg0XFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:10 INFO - Running setup.py (path:/tmp/pip-hh1WaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:55:10 INFO - Running setup.py install for manifestparser 04:55:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for mozcrash 04:55:11 INFO - Running setup.py install for mozdebug 04:55:11 INFO - Running setup.py install for mozdevice 04:55:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for mozfile 04:55:11 INFO - Running setup.py install for mozhttpd 04:55:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for mozinfo 04:55:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for mozInstall 04:55:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for mozleak 04:55:11 INFO - Running setup.py install for mozlog 04:55:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:55:11 INFO - Running setup.py install for moznetwork 04:55:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Running setup.py install for mozprocess 04:55:12 INFO - Running setup.py install for mozprofile 04:55:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Running setup.py install for mozrunner 04:55:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Running setup.py install for mozscreenshot 04:55:12 INFO - Running setup.py install for moztest 04:55:12 INFO - Running setup.py install for mozversion 04:55:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:55:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:55:12 INFO - Cleaning up... 04:55:12 INFO - Return code: 0 04:55:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:12 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:12 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 04:55:12 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 04:55:12 INFO - Using env: {'DISPLAY': ':0', 04:55:12 INFO - 'HOME': '/home/cltbld', 04:55:12 INFO - 'LANG': 'en_US.UTF-8', 04:55:12 INFO - 'LANGUAGE': 'en_US:en', 04:55:12 INFO - 'LOGNAME': 'cltbld', 04:55:12 INFO - 'MAIL': '/var/mail/cltbld', 04:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:12 INFO - 'MOZ_NO_REMOTE': '1', 04:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:12 INFO - 'NO_EM_RESTART': '1', 04:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:12 INFO - 'PWD': '/builds/slave/test', 04:55:12 INFO - 'SHELL': '/bin/bash', 04:55:12 INFO - 'SHLVL': '1', 04:55:12 INFO - 'TERM': 'linux', 04:55:12 INFO - 'TMOUT': '86400', 04:55:12 INFO - 'USER': 'cltbld', 04:55:12 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:12 INFO - '_': '/tools/buildbot/bin/python'} 04:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:12 INFO - Running setup.py (path:/tmp/pip-ugGDQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:13 INFO - Running setup.py (path:/tmp/pip-YckR98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:13 INFO - Running setup.py (path:/tmp/pip-3FU7FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:13 INFO - Running setup.py (path:/tmp/pip-GXJcIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:13 INFO - Running setup.py (path:/tmp/pip-oTr3r5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:13 INFO - Running setup.py (path:/tmp/pip-DXlZy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:13 INFO - Running setup.py (path:/tmp/pip-b9hNbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:13 INFO - Running setup.py (path:/tmp/pip-qF3tr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:13 INFO - Running setup.py (path:/tmp/pip-gPB8HR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:13 INFO - Running setup.py (path:/tmp/pip-fWisfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:13 INFO - Running setup.py (path:/tmp/pip-yo0Ee1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:13 INFO - Running setup.py (path:/tmp/pip-1PX41J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:13 INFO - Running setup.py (path:/tmp/pip-RzcD_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:13 INFO - Running setup.py (path:/tmp/pip-DmFQG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:13 INFO - Running setup.py (path:/tmp/pip-Sw2j_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:14 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)) 04:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:14 INFO - Running setup.py (path:/tmp/pip-eBF6e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:14 INFO - Running setup.py (path:/tmp/pip-Qq7wgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:14 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)) 04:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:55:14 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)) 04:55:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:55:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:55:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55:16 INFO - Downloading blessings-1.6.tar.gz 04:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:55:16 INFO - Installing collected packages: blessings 04:55:16 INFO - Running setup.py install for blessings 04:55:16 INFO - Successfully installed blessings 04:55:16 INFO - Cleaning up... 04:55:16 INFO - Return code: 0 04:55:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:16 INFO - Reading from file tmpfile_stdout 04:55:16 INFO - Current package versions: 04:55:16 INFO - argparse == 1.2.1 04:55:16 INFO - blessings == 1.6 04:55:16 INFO - blobuploader == 1.2.4 04:55:16 INFO - docopt == 0.6.1 04:55:16 INFO - functools32 == 3.2.3-2 04:55:16 INFO - jsonschema == 2.5.1 04:55:16 INFO - manifestparser == 1.1 04:55:16 INFO - mozInstall == 1.12 04:55:16 INFO - mozcrash == 1.0 04:55:16 INFO - mozdebug == 0.1 04:55:16 INFO - mozdevice == 0.49 04:55:16 INFO - mozfile == 1.2 04:55:16 INFO - mozhttpd == 0.7 04:55:16 INFO - mozinfo == 0.9 04:55:16 INFO - mozleak == 0.1 04:55:16 INFO - mozlog == 3.4 04:55:16 INFO - moznetwork == 0.27 04:55:16 INFO - mozprocess == 0.24 04:55:16 INFO - mozprofile == 0.28 04:55:16 INFO - mozrunner == 6.13 04:55:16 INFO - mozscreenshot == 0.1 04:55:16 INFO - mozsystemmonitor == 0.3 04:55:16 INFO - moztest == 0.8 04:55:16 INFO - mozversion == 1.4 04:55:16 INFO - psutil == 3.1.1 04:55:16 INFO - requests == 1.2.3 04:55:16 INFO - wsgiref == 0.1.2 04:55:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:16 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')]} 04:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:16 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')]} 04:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:16 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:16 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 04:55:16 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 04:55:16 INFO - Using env: {'DISPLAY': ':0', 04:55:16 INFO - 'HOME': '/home/cltbld', 04:55:16 INFO - 'LANG': 'en_US.UTF-8', 04:55:16 INFO - 'LANGUAGE': 'en_US:en', 04:55:16 INFO - 'LOGNAME': 'cltbld', 04:55:16 INFO - 'MAIL': '/var/mail/cltbld', 04:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:16 INFO - 'MOZ_NO_REMOTE': '1', 04:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:16 INFO - 'NO_EM_RESTART': '1', 04:55:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:16 INFO - 'PWD': '/builds/slave/test', 04:55:16 INFO - 'SHELL': '/bin/bash', 04:55:16 INFO - 'SHLVL': '1', 04:55:16 INFO - 'TERM': 'linux', 04:55:16 INFO - 'TMOUT': '86400', 04:55:16 INFO - 'USER': 'cltbld', 04:55:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:16 INFO - '_': '/tools/buildbot/bin/python'} 04:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 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)) 04:55:16 INFO - Cleaning up... 04:55:16 INFO - Return code: 0 04:55:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:55:16 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')]} 04:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:16 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')]} 04:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:16 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 0x287a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b18310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', '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 04:55:16 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 04:55:16 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 04:55:16 INFO - Using env: {'DISPLAY': ':0', 04:55:16 INFO - 'HOME': '/home/cltbld', 04:55:16 INFO - 'LANG': 'en_US.UTF-8', 04:55:16 INFO - 'LANGUAGE': 'en_US:en', 04:55:16 INFO - 'LOGNAME': 'cltbld', 04:55:16 INFO - 'MAIL': '/var/mail/cltbld', 04:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:16 INFO - 'MOZ_NO_REMOTE': '1', 04:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:16 INFO - 'NO_EM_RESTART': '1', 04:55:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:16 INFO - 'PWD': '/builds/slave/test', 04:55:16 INFO - 'SHELL': '/bin/bash', 04:55:16 INFO - 'SHLVL': '1', 04:55:16 INFO - 'TERM': 'linux', 04:55:16 INFO - 'TMOUT': '86400', 04:55:16 INFO - 'USER': 'cltbld', 04:55:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:16 INFO - '_': '/tools/buildbot/bin/python'} 04:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:55:17 INFO - Cleaning up... 04:55:17 INFO - Return code: 0 04:55:17 INFO - Running post-action listener: _resource_record_post_action 04:55:17 INFO - Running post-action listener: _start_resource_monitoring 04:55:17 INFO - Starting resource monitoring. 04:55:17 INFO - [mozharness: 2017-02-17 12:55:17.168973Z] Finished create-virtualenv step (success) 04:55:17 INFO - [mozharness: 2017-02-17 12:55:17.169446Z] Running install step. 04:55:17 INFO - Running pre-action listener: _resource_record_pre_action 04:55:17 INFO - Running main action method: install 04:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:17 INFO - Reading from file tmpfile_stdout 04:55:17 INFO - Detecting whether we're running mozinstall >=1.0... 04:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:55:17 INFO - Reading from file tmpfile_stdout 04:55:17 INFO - Output received: 04:55:17 INFO - Usage: mozinstall [options] installer 04:55:17 INFO - Options: 04:55:17 INFO - -h, --help show this help message and exit 04:55:17 INFO - -d DEST, --destination=DEST 04:55:17 INFO - Directory to install application into. [default: 04:55:17 INFO - "/builds/slave/test"] 04:55:17 INFO - --app=APP Application being installed. [default: firefox] 04:55:17 INFO - mkdir: /builds/slave/test/build/application 04:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:55:32 INFO - Reading from file tmpfile_stdout 04:55:32 INFO - Output received: 04:55:32 INFO - /builds/slave/test/build/application/firefox/firefox 04:55:32 INFO - Running post-action listener: _resource_record_post_action 04:55:32 INFO - [mozharness: 2017-02-17 12:55:32.859140Z] Finished install step (success) 04:55:32 INFO - [mozharness: 2017-02-17 12:55:32.859416Z] Running run-tests step. 04:55:32 INFO - Running pre-action listener: _resource_record_pre_action 04:55:32 INFO - Running main action method: run_tests 04:55:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:55:32 INFO - Python 2.7.3 04:55:32 INFO - Return code: 0 04:55:32 INFO - grabbing minidump binary from tooltool 04:55: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')]} 04:55:32 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 0x2b15400>, '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 0x2b163b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b16840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:55:32 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 04:55:32 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 04:55:32 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 04:55:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:55:32 INFO - Return code: 0 04:55:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:55:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:55:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:55:32 INFO - ENV: RUST_BACKTRACE is now 1 04:55:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:55:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:55:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 04:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 04:55:32 INFO - Using env: {'DISPLAY': ':0', 04:55:32 INFO - 'HOME': '/home/cltbld', 04:55:32 INFO - 'LANG': 'en_US.UTF-8', 04:55:32 INFO - 'LANGUAGE': 'en_US:en', 04:55:32 INFO - 'LOGNAME': 'cltbld', 04:55:32 INFO - 'MAIL': '/var/mail/cltbld', 04:55:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:55:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:32 INFO - 'MOZ_NO_REMOTE': '1', 04:55:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:55:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:55:32 INFO - 'NO_EM_RESTART': '1', 04:55:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:32 INFO - 'PWD': '/builds/slave/test', 04:55:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:55:32 INFO - 'RUST_BACKTRACE': '1', 04:55:32 INFO - 'SHELL': '/bin/bash', 04:55:32 INFO - 'SHLVL': '1', 04:55:32 INFO - 'TERM': 'linux', 04:55:32 INFO - 'TMOUT': '86400', 04:55:32 INFO - 'USER': 'cltbld', 04:55:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087', 04:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:32 INFO - '_': '/tools/buildbot/bin/python'} 04:55:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 04:55:33 INFO - mozversion application_buildid: 20170217122519 04:55:33 INFO - mozversion application_changeset: 03fc0a686d3f8e0c38136771579d030c7a8c0b82 04:55:33 INFO - mozversion application_display_name: Nightly 04:55:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:55:33 INFO - mozversion application_name: Firefox 04:55:33 INFO - mozversion application_remotingname: firefox 04:55:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:55:33 INFO - mozversion application_vendor: Mozilla 04:55:33 INFO - mozversion application_version: 54.0a1 04:55:33 INFO - mozversion platform_buildid: 20170217122519 04:55:33 INFO - mozversion platform_changeset: 03fc0a686d3f8e0c38136771579d030c7a8c0b82 04:55:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:55:33 INFO - mozversion platform_version: 54.0a1 04:55:33 INFO - using testdate: 1487336133 04:55:33 INFO - actual date: 1487336133 04:55:33 INFO - starting webserver on 'localhost:52630' 04:55:33 INFO - SUITE-START | Running 2 tests 04:55:33 INFO - TEST-START | dromaeo_css 04:55:33 INFO - operating with platform_type : linux_ 04:55:33 INFO - Initialising browser for dromaeo_css test... 04:55:33 INFO - TEST-INFO | started process 30818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8BjnW9/profile http://localhost:52630/getInfo.html) 04:55:37 INFO - PROCESS | 30818 | Iterating over profile /tmp/tmp8BjnW9/profile 04:55:38 INFO - PROCESS | 30818 | __metrics Screen width/height:1600/1200 04:55:38 INFO - PROCESS | 30818 | colorDepth:24 04:55:38 INFO - PROCESS | 30818 | Browser inner width/height: 1024/697 04:55:38 INFO - PROCESS | 30818 | __metrics 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/frequencyCap.json = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/extensions.ini = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/bookmarkbackups = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/blocklist.xml = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/key3.db = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/cookies.sqlite = Fri Feb 17 2017 04:55:37 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/safebrowsing = Fri Feb 17 2017 04:55:38 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/extensions.json = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/places.sqlite-wal = Fri Feb 17 2017 04:55:38 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/cert8.db = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/directoryLinks.json = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/mimeTypes.rdf = Fri Feb 17 2017 04:55:37 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/cache2 = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/user.js = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/pluginreg.dat = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/crashes = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | Stat failure 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/permissions.sqlite = Fri Feb 17 2017 04:55:34 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/compatibility.ini = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/gmp = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/addons.json = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/localstore.rdf = Fri Feb 17 2017 04:54:52 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/.parentlock = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/thumbnails = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/places.sqlite = Fri Feb 17 2017 04:55:38 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/secmod.db = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/minidumps = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/content-prefs.sqlite = Fri Feb 17 2017 04:55:36 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/places.sqlite-shm = Fri Feb 17 2017 04:55:34 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/prefs.js = Fri Feb 17 2017 04:54:52 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/startupCache = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/sessionCheckpoints.json = Fri Feb 17 2017 04:55:38 GMT-0800 (PST) 04:55:38 INFO - PROCESS | 30818 | No birth date. Using mtime. 04:55:38 INFO - PROCESS | 30818 | Using date: /tmp/tmp8BjnW9/profile/extensions = Fri Feb 17 2017 04:55:33 GMT-0800 (PST) 04:55:39 INFO - PROCESS | 30818 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:55:39 INFO - PROCESS | 30818 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:55:40 INFO - TEST-INFO | 30818: exit 0 04:55:40 INFO - Browser initialized. 04:55:40 INFO - Running cycle 1/1 for dromaeo_css test... 04:55:40 INFO - TEST-INFO | started process 30908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8BjnW9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:52 INFO - PROCESS | 30908 | RSS: Main: 151101440 04:55:52 INFO - PROCESS | 30908 | 04:58:58 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52630/tests/dromaeo/cssquery-ext.html) 04:59:01 INFO - PROCESS | 30908 | RSS: Main: 354684928 04:59:01 INFO - PROCESS | 30908 | 05:00:42 INFO - PROCESS | 30908 | ************************* 05:00:42 INFO - PROCESS | 30908 | A coding exception was thrown and uncaught in a Task. 05:00:42 INFO - PROCESS | 30908 | 05:00:42 ERROR - PROCESS | 30908 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:00:42 INFO - PROCESS | 30908 | Full stack: 05:00:42 INFO - PROCESS | 30908 | ************************* 05:02:04 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-ext.html (next: http://localhost:52630/tests/dromaeo/cssquery-jquery.html) 05:02:04 INFO - PROCESS | 30908 | RSS: Main: 242417664 05:02:04 INFO - PROCESS | 30908 | 05:05:07 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52630/tests/dromaeo/cssquery-mootools.html) 05:05:07 INFO - PROCESS | 30908 | RSS: Main: 223383552 05:05:07 INFO - PROCESS | 30908 | 05:08:10 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52630/tests/dromaeo/cssquery-prototype.html) 05:08:10 INFO - PROCESS | 30908 | RSS: Main: 181690368 05:08:10 INFO - PROCESS | 30908 | 05:11:13 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52630/tests/dromaeo/cssquery-yui.html) 05:11:14 INFO - PROCESS | 30908 | RSS: Main: 185090048 05:11:14 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-yui.html (next: http://localhost:52630/tests/dromaeo/cssquery-dojo.html) 05:14:17 INFO - PROCESS | 30908 | RSS: Main: 282206208 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | __start_tp_report 05:14:17 INFO - PROCESS | 30908 | _x_x_mozilla_page_load 05:14:17 INFO - PROCESS | 30908 | _x_x_mozilla_page_load_details 05:14:17 INFO - PROCESS | 30908 | |i|pagename|runs| 05:14:17 INFO - PROCESS | 30908 | |0;dojo.html;2588.6893572771264;2604.3489127718067;2633.9736602633966;2637.8285411448264;2664.9600255996165;4609.377734005908;4638.0491999140095;4720.457147428043;4743.169945259574;4796.928046079307;4566.063956888838;4596.563326483979;4640.4431468223775;4652.6975746576445;4683.672142949995;4040.272750904836;4221.345691417828;4437.955620443802;4438.600525952664;4459;4184.832606695713;4353.0423306872435;4391.5827996340295;4456.55434456555;4481.820727170909;4202.873913782591;4215;4242.745435273893;4246.044639956016;4254;4243.554426785205;4244.639205667522;4276.2089013532595;4298.441202643668;4344.63070638996;4288.549702281247;4310.52584215736;4346.195953748566;4352.173087113439;4367.192069467159;4696.154692155411;4707.105649926537;4710.8351207707565;4721.834735784231;4732.550407711262;4852.902941941142;5015.774290156952;5017.37282839645;5026.47222041684;5037.647364684473;4908.754562271872;4953.6037117030555;4973.925391119136;4977.328060711794;4983.750812459399;4599.35428517578;4634.096351211549;4648.140094082605;4654.697444666086;4663.603593694506;5081.161608334583;5090.618203634742;5102.744862756847;5105.030044291572;5105.106606343875;5014.874628134326;5028.019536190408;5081.4918508148885;5094.337736094284;5098.056859481007;5121.411037730709;5123.641345105807;5125.64120511568;5130.4356520782685;5177.326947496801;4892.48628893961;5085.923711144335;5095.668781529189;5098.694078355311;5103;4896.95103048972;4955.702657840541;4965.379327584052;4975.850724478271;5020.297158397827;4324.308110702273;4385.079133382025;4393.406890069864;4393.802278897458;4396.054848207651;3776.6034566370136;3780.8676696315497;3780.9243815123546;3811.5807261201244;3820.388737801994;4456.9331460028125;4466.793965629262;4469.240229161051;4490.461144662596;4505.211587972091;4931.778069986859;4933.802647894116;4954.281629163751;5017.648764586444;5123.308353372247;4313.784310784448;4364.62900653448;4369.169857727022;4372.23485889968;4374.321980093091;4471.5081341052455;4501.0547784964865;4513.887152821206;4548.090381923628;4561.703489273186;4736.123817093849;4741.5495527924795;4746.644001699818;4747.264174053027;4782.665213435026;4884.291777692144;4920.507949205051;4922.581580565548;4995.625328100335;5044.571211447068;4696.906061878812;4712.293156026623;4713.057388522241;4717.787699553597;4718.693284936468;4898.9020219594695;4904.754762261801;4905.23968784839;4908.092002979461;4911.803527858846;4973.5523802857915;4973.875653108701;4977.825776097964;4978.925316120189;5090.669106508065;4983.5763960063805;5036.445990941067;5047.369078865142;5066.544011039025;5069;5030.622703297194;5044.319016932667;5053.873653158554;5055.721935293447;5069.847904562869;4802.7838747255755;4961.479044700399;4967.254911763264;4973.229149481836;5021.49785021495;4918.114739346952;4972.726500042532;4976.452590215001;4976.975115124401;4977.800887964441;4868.464468908488;4870.05034018363;4870.9269360960325;4876.341693871282;4881.31661567388 05:14:17 INFO - PROCESS | 30908 | |1;ext.html;559.8796258804356;568.573569822633;573.406524247404;596.3916804859041;598.5570677698504;26604.334891627695;28591.714082859166;36879;39067.21865562687;39348;38749.41875871864;38780.41829372562;38785;38875.80562097193;38896.41655375172;7383.150937642173;7553.244675532458;7556.773296801105;7570.6909392635225;7645.694172233104;6747.696353664097;6930.757423490179;9425.575849086774;9590.376625519319;9632.988536203702;9534.189593884528;9579.80840383192;9582.664606738765;9583.28125390595;9588.664396746115;9480.762980925463;9530.285228607881;9537.666181683677;9544;9557.092076252746;9360.391574547633;9362;9397.295202859745;9423.858642120374;9444.433333999914;34230.48654270188;34560.13599660004;34589.82705086459;34603.4809477858;34667.13332166691;3444.48332750087;3498.1079824644553;3505.964940350589;3516.1209697575605;3559.661832125944;23829;24849;25315;25442.236732898044;26872.59691104635;3270.460374038281;3376.1929481292136;3487.459443786192;3519.665631764978;3628.1655219299446;230648;233095;233735;234255;236054.819725902;38929;45113.32330014987;45113.548864510936;45231.54768452339;45413.772931135136;38625.42061869074;56263.71868140715;61703.38296617066;62861;66854.33145668579;10884.346939183675;12023.51318117736;12117.151799374136;12134.211276267015;12395.070369722307;8072.354211663171;9015.819683606422;9266.860997085048;9270.119338662817;9436.811263774685;18380.264789408542;18536.44390668282;18557.814421855608;18613.81386186148;18638.720419193724;7086.610236437045;7195.89091744867;7254.709811607477;7268.382187514015;7376.819708846559;746.1046743907225;748.221849276757;749.7900587835414;765.3226894198674;765.3685709289747;26108.216753497047;27057;27406.862965685043;27584.862075689896;27614.309642258704;861.2468709023657;862.4394143806576;863.6070587882498;872.1888643561422;1031.6750223679517;13899.79150312746;14261.215633140277;14308.354539227914;14459.349329280207;14503;129064.35467822789;129163.35418322848;129629;129959.35020325027;130208;48158.75920620444;48722;48770.75614621904;48876.266855997186;48906.75546622244;1138.9145814063813;1160.1356989043047;1162.7151347919475;1174.6358628825092;1190.0301254477747;24440;25398.365040874127;26173.476530468903;26404.471910561297;26445.33886652849;40520.18959620732;40801.59198407978;40821.38767918424;40922.386164208154;40970.59029409668;268985;269386;269765;270042;290885;26596.734032659424;34552;35558.111047224025;35690.64309356873;37247;35314.82342588271;35722.10694732548;36791.63208367882;38761.418578720775;39522.2095558093;30111.698883011766;30120;30745.53881691821;30926.690733092382;31789.8410507946;30826.383472330876;30870.53694194634;30870.845645771627;30943.845280773454;32020.83989580037;29145.562816557343;30008.69991300059;30130.24674383158;30320.39359212759;30603.54094688626;26195.73804261933;27763.44473110486;27804.58293125564;27817.582736259377;27868.581971270854;22018.44953876166;22056.669149962443;22101.889490552443;22187.77812221857;22220.888895555418 05:14:17 INFO - PROCESS | 30908 | |2;jquery.html;358.62165415486686;383.22205921978394;388.718179319993;389.57730862014705;421.72166370195674;12404.255744655316;12743.426545805438;13359.19844809311;17705.822941770577;18405.26378944844;18045.63908721825;18131.546711332223;18227.72658410125;18308.633827323447;18362.1737021834;5099.107656160167;5271.815486457975;5304.124819404794;5305;5306.389765176996;4948.628852836034;5026.095302845486;5072.4166720827025;5176.948230517684;5251.711155886424;5046.54581087702;5046.747662616873;5047.520485553885;5049.065922804293;5049.24261360797;4869.196582563873;4957.5786058185095;5043.39479262489;5047.041062198171;5054.5198206170535;4567.0409214065085;4574.016586433932;4578.816847326103;4580.793864276082;4589.495155532889;19650.606987860305;20348.898255508775;20454.181832726674;21590.460238494004;21592.35222943314;2153.9461513462134;2203.2839327218558;2209.7348318201757;2221.589006033889;2275.5448910218024;9613.182879455255;9750.853737193947;9888.159506441962;11111.555537778479;12380.938095309555;1606.775051492792;1727.5594723345591;1745.895246285227;1843.0647819789301;1875.9906200469047;59602.99999999956;61724.382756172316;67555;73597.63201184013;77650;9170.49562274068;9344.972053074156;9637.542426681151;9722.9027709722;9773.169280611228;13209.73580528384;13211.537596184087;13226.206427614374;13262.27057511846;13357.065005449525;6310.1796766420075;6443.710033048524;6501.934980650133;6680.432163266079;6681.532292739462;5799.942000580025;5805.10020946754;5926.1999630049395;6024.3975602439405;6025.758969641165;3986.760794352348;4031.07285324377;4056.3712624542654;4236.745795252295;4426.690131690815;1845.8154184581704;1851.176591057807;1853.981460185408;1893.7064754963003;1899.2329253573355;747.9626018699153;773.6750564762813;775.4455564271573;781.6716978868889;794.5232860283899;16439.589010274598;16845;17395.304187832597;17527.474175774747;17843;758.0372926383573;759.4266328921628;765.9923400766032;777.7472321495536;786.469133335008;8691.826163476695;9680.903190968;9723.854142187873;9735.123838854537;9826.263030272758;440573;446698;452388;457967;459365.703171482;81989;84466.57766711127;88292.5585372069;89441.10558894459;90037.54981225052;878.7495563764296;888.8888888888889;908.4049947284672;909.3907082254773;910.1308250620641;14532.927335363256;14838.554843354284;15190.544283671508;15207.163606001333;15330.38678452894;27961.720382795913;28703.569446458736;30801;36125.81937090298;38402.61597383991;47426.52573474359;49484.25773613471;50056.749716251186;50651.746741266055;53145;5563.7774489019985;7008.983697363956;7436.96281518589;7480.850382992201;7799.9220007799195;8925.509096999727;11956.641300760992;12109.031277497956;12164.75670486603;12583.622491325275;10273.743156420851;10277.177825773771;10371.792564148524;10699.572017119539;10842.674719758419;10143.188544916537;10162.441065741456;10166.085052345325;10248.590056397661;10355.223358248011;10691.144708423151;10769.353838769699;10774.515146818569;10793.244472886823;11169.609063682732;6611.900821487778;7009.719611215494;7058.752943646948;7080.2919708028785;8260.545669988209;8729.607167677343;10375.740606484898;10488.26582139243;10536.736581585521;10882.40146791934 05:14:17 INFO - PROCESS | 30908 | |3;mootools.html;1180.893719565239;1341.979870301945;1347.791092380681;1377.3732951507066;1381.868722471365;6010.158577799111;7147.927101140669;7346.522476039054;7357.043584334043;7570.242975702427;7393.260673932604;7471.252874712526;7473.476856620039;7486.925130748691;7486.962565187167;2038.1745393115775;2078.272604588397;2079.07481170879;2080.4694802825334;2081.5524662197627;1774.8402643762058;1831.313257528427;2089.268755935426;2158.956820863582;2167.371462275938;2103.3374487036613;2106.8630539014994;2107.8419118566094;2111.144986280559;2114.397396741929;2053.9486512837148;2082.3961051295105;2084.145500344859;2085.1971990783504;2087.499000239947;2020.3534868841975;2043.8569300148995;2079.3034333498294;2079.864808787439;2081.2091405266056;7400.148982866932;7494.775156745306;7497.250274972514;7505.512141710826;7512.774616761506;4438.644908407319;4572.474165470947;4579.4504659440745;4581.083783243364;4585.197590421662;2705.4318593095472;2835.5888396182436;2847.857607119656;2887.754540864029;2898.449294634013;1148.7300484386246;1165.1669056624555;1689.7380905959474;1728.438257566296;1760;9810.117089461983;9922.553485093189;9938.204943604494;9939.65211217611;9962.302638815287;3938.3895496198134;3997.4603428537357;4114.0948991221885;4116.732412393185;4182.665386769051;7993.960030199812;8051.275385215359;8065.758027259191;8067.515949042959;8072.636731347104;4515.412996310524;4516.23224051912;4520.9827788747925;4528.0943811237885;4529.9547004530195;4026.510191229264;4157.97921010399;4213.578642135823;4215.873523794291;4216.978915105405;2904.1142451552478;2968.985155074211;2969.3615872587065;2988.237999310162;2993.1469531183507;902.5442151713474;956.1442508954447;986.329296078659;992.5880759484877;994.6866736977829;771.2750014985895;788.5150632361057;790.1229635105029;796.5579103597444;799.3046049936593;4626.421697287839;4719.663766435568;4754.168020596382;4768.666193366431;4836.371271734652;849.0405841399179;862.6714859117016;876.574861192334;891.1667590802621;909.9636014559344;3143.9528407073913;3190.760692948038;3237.627672817609;3286.2277364819247;3297.1427428868665;8626.568671566396;8947.507887066275;8947.865782013276;8961.686340978034;8966.327525435618;8778.429402088845;8884.73345799627;8885.378023538291;8898.4660920345;8908.153725396078;6926.238113807578;7006.369426751617;7015.193252775996;7015.824604385033;7026.191987921352;4617.722936623813;4706.694064885772;4794.328793968917;4810.494898035796;4812.374391329245;7704.345130663956;8004.559749213616;8094.028716554051;8137.186281371815;8140.104588495379;8057.476264042818;8217.178282171735;8297.502149870785;8303.916960830556;8435.24082832548;6324.715387807468;6454.1609590752905;6472.644004579605;6476.352364763486;6478;6937.583744975318;6951.2353641098425;6967.965160174167;6968.407685346802;7037.718491260497;6252.155958945484;6303.842403939938;6313.40022697843;6362.3319551448285;6420.6468644225015;6172.382761723792;6203.069539569101;6319.399657032574;6349.968250158904;6445.6454894981225;5287.048331300402;5360.16917377807;5362.410134885237;5412.404635490014;5427.5657947363325;4188.5811418857875;4195.077083041726;4278.486581610102;4300.16146851361;4432.202203603382;3984.9203015940097;4035.172789578071;4069.3895915612893;4091.734037287567;4191.392248124066 05:14:17 INFO - PROCESS | 30908 | |4;prototype.html;326.1666442240077;342.5598106433234;342.6265370745885;355.1210753385371;373.60771190250233;4718.787654555545;4807.302941073543;4812.326274321589;4817.445993710724;4818.975905120469;4733.171694953387;4739.284412010054;4745.454272758634;4756.405449318835;4764.5235476452335;3590.658887405705;3709.684676802475;3725.701943844486;3736.4021756518964;3834.8657796977113;3724.7020238381;3725.776453412804;3735.1409175889426;3750.2312026034597;3751.287255421475;3709.666130048295;3716.256748650267;3718.0998820919726;3723.609021052791;3726.9252866385204;3735.701143908481;3740.775553466787;3740.8877733668032;3749.850005999757;3763.8682646107254;3131.937361252785;3258.120307516957;3258.885938992124;3261.5433839262523;3281.343731253759;4623.329617205486;4625.722456652612;4626.1441633297945;4627.282771170474;4632.2819962905805;4229.900225941277;4242.896846819818;4251.893702657459;4252.4046633471335;4260.786960651986;4776.9697878401885;4925.679830810986;4985.825496107655;4989.55094041538;4994.350734404504;3595.388783906742;3595.982020089909;3596.3886139356373;3602.9279414411826;3614.4397618369194;5003.924941125886;5147.922781158286;5241.580673546107;5270.6837589744355;5275.446078161776;3515.6132825389186;3526.523919270917;3554.085649767166;3558.146044949245;3683.1344634010984;3720.479132921393;3734.6452087051684;3735.9813200933822;3736.5142531470733;3751.9812400938367;4243.27864263076;4249.532551419341;4251.341042138473;4252.192083504125;4260;4886.755662216875;5014.348134742533;5016.398032236155;5063.797448102108;5072.289879416885;1335.0721248732038;1337.9898176876395;1351.3378444562093;1355.5120156743565;1363.5909227231791;943.7020854219996;982;982.7739619887466;986.1814693804125;995.2983146881431;4310.892227694333;4326.841405773803;4342.261815491374;4351.36905148758;4359.888228501712;4998.300917504507;5005.7997680092385;5049.671771334852;5051.368578927634;5079.720615366116;4173.7495750254475;4332.393464914914;4339.132173565299;4346.869593912188;4347.543507931685;2656.7343265673667;2659.6808382994163;2672.50558646651;2700.8919643214444;2708.404151086758;4614.169449499055;4690.771883355447;4697.389339385926;4708.270218116198;4726.929096063562;5005.299258103868;5103.050041459778;5202.297689811902;5222.686638801684;5234.26720259164;4882.829100981519;4894.143524883202;4895.747955940236;4911;4921.8523444296725;5140.948590514047;5157.355330583677;5182.714950677749;5188.870278242923;5189.247559103985;4827.972251654383;5025.597952163891;5040.924386134137;5053.408176424415;5074.517920797518;4647.372604698322;4770.236762118045;4850.417949846041;4866.9269960949905;4890.706557606555;3617.855285788539;3676.4852920591675;3697.6487233712755;3708.64501041835;3719.237556301006;5158.638895277295;5188.068741161232;5209.192575150858;5210.322658054429;5216.452272511332;4892.31507588945;4976.651634385558;4997.250412438163;5007.073691366963;5057.848264552069;4934.777934992862;4995.675281106862;4998.2002879539095;5007.271037284616;5028.220625803006;4947.727874966768;4950.702957822542;4981.476944920876;4992.700437973734;4994.300797888372;4825;5024.120778863758;5039.420466646383;5045.747712614428;5087.592992560661;4926.374296482224;4927.38278362674;4937.679050861682;4938.259261110862;4947.678400903929 05:14:17 INFO - PROCESS | 30908 | |5;yui.html;641.9807405777829;656.9934300656995;714.1216303946146;734.6473692627538;750.4296734481794;12262;13138.408771605276;13159.60521184366;13203.801942970851;13290.601281961544;13048.804267935988;13224.074314797968;13228.338583070856;13251.602451926434;13253.867461325408;805.4321703199231;828.6478246745139;829.5396055189367;829.6142293833366;836.456303402787;832.063928080909;834.7120243515977;834.9749507514784;835.4820011592791;835.678263868412;870.5995242188601;871.1158174452904;871.2898987325341;871.6339137562207;872.2193636695154;863.7451951674271;865.1348651348651;865.4677373415336;865.8831057807209;866.9046404895456;637.2512298049772;638.7093872288098;638.8179368879847;638.955273130881;639.5299454900644;13097.607071787861;13534.93232533831;14053.85946140546;14089.436422543085;14092.647683807987;13048.86951130486;13568;13583.796243056264;13661.521846735317;13691.520796772165;10162.542685579167;10332.845007324897;10451.477426128664;10646.57413703451;10705.25063245573;3175.063356309894;3194;3222.2910959588853;3228.6287076985977;3235.854386552611;58767;59016.70491647557;60729.39270607282;61685.6915715423;62009.37990620126;9100.180983711498;9259.490728009889;9272.907270927339;9321.813563728687;9706.466144362128;17247.913760431118;17293.740593891103;17297.740533892003;17300.48098557045;17327.2202750874;6152.692365381802;6292.653904035231;6488.124103246001;6544.574602650812;6551.263915062512;3437.089171369589;3491.825408729553;3619.746617736786;3621.9818900905852;3623.5289412376223;3003.834789086621;3079.5380692895797;3083.2446050717717;3089.0115163148025;3089.1504836170234;474.8124490826173;477.87097483679213;482.16103979076854;482.17788670317026;482.22121274142006;516.4190285928331;521.405597618715;525.2593842681877;535.7107162132405;545.7680485793522;7969.561674107979;8423.70517031901;8968.730938071869;9060.003399626035;9246.583903724348;478.4545617995488;497.8182963218399;502.24161516110206;507.1910303066603;520.3079903728033;4070.369092790563;4150.335946248586;4265.3815196796295;4289.120730250291;4333.501647310537;51389.860790590785;51685.48314516882;65134.67432662806;65232;67757.6612116946;55099.72450137723;55418.44581554133;55798.16302755462;56250;56652;50563.747181264596;52038.73980630073;52462.95074098497;52545.47454525406;53166.73416632892;7954.482958607672;8222.424430289822;8242.258196762321;8352.206540378655;8404.53775042354;35512.962796325206;35692.46461303031;36621.816890915376;36842.63157368392;37401.81299093596;34697.479537806445;42723.57276427196;43022.56977430185;44246.98630341465;44376.7781161105;9849.60601575929;10045;10193.949030254802;10223.539940702538;10451.790964180826;10695.538384225387;10718.196135289729;10984.890151098387;11028.558857645605;11177.944110279397;9863.506824658882;10387.636432724817;10388.636397726043;10431.634892778717;10797.406142662228;10808.621698240524;10964.396958167377;11015.889841101487;11087.279326843729;11114.88885111171;10412.32319899204;10568.260221784401;10633.468326583485;10649.307794993301;10654.62708805188;8605.82788344217;8763.430377025474;8892.466452012899;9158.77103072402;9298.488583127993;8316.326277267171;8414.915850841659;8840.704718281193;9100;9121.179093881583 05:14:17 INFO - PROCESS | 30908 | __end_tp_report 05:14:17 INFO - PROCESS | 30908 | __start_cc_report 05:14:17 INFO - PROCESS | 30908 | _x_x_mozilla_cycle_collect,2847 05:14:17 INFO - PROCESS | 30908 | __end_cc_report 05:14:17 INFO - PROCESS | 30908 | __startTimestamp1487337257885__endTimestamp 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | ------- Summary: start ------- 05:14:17 INFO - PROCESS | 30908 | Number of tests: 6 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#0] dojo.html Cycles:165 Average:4661.46 Median:4747.26 stddev:486.37 (10.2%) stddev-sans-first:459.88 05:14:17 INFO - PROCESS | 30908 | Values: 2588.7 2604.3 2634.0 2637.8 2665.0 4609.4 4638.0 4720.5 4743.2 4796.9 4566.1 4596.6 4640.4 4652.7 4683.7 4040.3 4221.3 4438.0 4438.6 4459.0 4184.8 4353.0 4391.6 4456.6 4481.8 4202.9 4215.0 4242.7 4246.0 4254.0 4243.6 4244.6 4276.2 4298.4 4344.6 4288.5 4310.5 4346.2 4352.2 4367.2 4696.2 4707.1 4710.8 4721.8 4732.6 4852.9 5015.8 5017.4 5026.5 5037.6 4908.8 4953.6 4973.9 4977.3 4983.8 4599.4 4634.1 4648.1 4654.7 4663.6 5081.2 5090.6 5102.7 5105.0 5105.1 5014.9 5028.0 5081.5 5094.3 5098.1 5121.4 5123.6 5125.6 5130.4 5177.3 4892.5 5085.9 5095.7 5098.7 5103.0 4897.0 4955.7 4965.4 4975.9 5020.3 4324.3 4385.1 4393.4 4393.8 4396.1 3776.6 3780.9 3780.9 3811.6 3820.4 4456.9 4466.8 4469.2 4490.5 4505.2 4931.8 4933.8 4954.3 5017.6 5123.3 4313.8 4364.6 4369.2 4372.2 4374.3 4471.5 4501.1 4513.9 4548.1 4561.7 4736.1 4741.5 4746.6 4747.3 4782.7 4884.3 4920.5 4922.6 4995.6 5044.6 4696.9 4712.3 4713.1 4717.8 4718.7 4898.9 4904.8 4905.2 4908.1 4911.8 4973.6 4973.9 4977.8 4978.9 5090.7 4983.6 5036.4 5047.4 5066.5 5069.0 5030.6 5044.3 5053.9 5055.7 5069.8 4802.8 4961.5 4967.3 4973.2 5021.5 4918.1 4972.7 4976.5 4977.0 4977.8 4868.5 4870.1 4870.9 4876.3 4881.3 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#1] ext.html Cycles:180 Average:37273.04 Median:3511.04 stddev:57783.50 (1645.8%) stddev-sans-first:57879.84 05:14:17 INFO - PROCESS | 30908 | Values: 559.9 568.6 573.4 596.4 598.6 26604.3 28591.7 36879.0 39067.2 39348.0 38749.4 38780.4 38785.0 38875.8 38896.4 7383.2 7553.2 7556.8 7570.7 7645.7 6747.7 6930.8 9425.6 9590.4 9633.0 9534.2 9579.8 9582.7 9583.3 9588.7 9480.8 9530.3 9537.7 9544.0 9557.1 9360.4 9362.0 9397.3 9423.9 9444.4 34230.5 34560.1 34589.8 34603.5 34667.1 3444.5 3498.1 3506.0 3516.1 3559.7 23829.0 24849.0 25315.0 25442.2 26872.6 3270.5 3376.2 3487.5 3519.7 3628.2 230648.0 233095.0 233735.0 234255.0 236054.8 38929.0 45113.3 45113.5 45231.5 45413.8 38625.4 56263.7 61703.4 62861.0 66854.3 10884.3 12023.5 12117.2 12134.2 12395.1 8072.4 9015.8 9266.9 9270.1 9436.8 18380.3 18536.4 18557.8 18613.8 18638.7 7086.6 7195.9 7254.7 7268.4 7376.8 746.1 748.2 749.8 765.3 765.4 26108.2 27057.0 27406.9 27584.9 27614.3 861.2 862.4 863.6 872.2 1031.7 13899.8 14261.2 14308.4 14459.3 14503.0 129064.4 129163.4 129629.0 129959.4 130208.0 48158.8 48722.0 48770.8 48876.3 48906.8 1138.9 1160.1 1162.7 1174.6 1190.0 24440.0 25398.4 26173.5 26404.5 26445.3 40520.2 40801.6 40821.4 40922.4 40970.6 268985.0 269386.0 269765.0 270042.0 290885.0 26596.7 34552.0 35558.1 35690.6 37247.0 35314.8 35722.1 36791.6 38761.4 39522.2 30111.7 30120.0 30745.5 30926.7 31789.8 30826.4 30870.5 30870.8 30943.8 32020.8 29145.6 30008.7 30130.2 30320.4 30603.5 26195.7 27763.4 27804.6 27817.6 27868.6 22018.4 22056.7 22101.9 22187.8 22220.9 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#2] jquery.html Cycles:180 Average:25988.19 Median:231272.91 stddev:74406.25 (32.2%) stddev-sans-first:74590.09 05:14:17 INFO - PROCESS | 30908 | Values: 358.6 383.2 388.7 389.6 421.7 12404.3 12743.4 13359.2 17705.8 18405.3 18045.6 18131.5 18227.7 18308.6 18362.2 5099.1 5271.8 5304.1 5305.0 5306.4 4948.6 5026.1 5072.4 5176.9 5251.7 5046.5 5046.7 5047.5 5049.1 5049.2 4869.2 4957.6 5043.4 5047.0 5054.5 4567.0 4574.0 4578.8 4580.8 4589.5 19650.6 20348.9 20454.2 21590.5 21592.4 2153.9 2203.3 2209.7 2221.6 2275.5 9613.2 9750.9 9888.2 11111.6 12380.9 1606.8 1727.6 1745.9 1843.1 1876.0 59603.0 61724.4 67555.0 73597.6 77650.0 9170.5 9345.0 9637.5 9722.9 9773.2 13209.7 13211.5 13226.2 13262.3 13357.1 6310.2 6443.7 6501.9 6680.4 6681.5 5799.9 5805.1 5926.2 6024.4 6025.8 3986.8 4031.1 4056.4 4236.7 4426.7 1845.8 1851.2 1854.0 1893.7 1899.2 748.0 773.7 775.4 781.7 794.5 16439.6 16845.0 17395.3 17527.5 17843.0 758.0 759.4 766.0 777.7 786.5 8691.8 9680.9 9723.9 9735.1 9826.3 440573.0 446698.0 452388.0 457967.0 459365.7 81989.0 84466.6 88292.6 89441.1 90037.5 878.7 888.9 908.4 909.4 910.1 14532.9 14838.6 15190.5 15207.2 15330.4 27961.7 28703.6 30801.0 36125.8 38402.6 47426.5 49484.3 50056.7 50651.7 53145.0 5563.8 7009.0 7437.0 7480.9 7799.9 8925.5 11956.6 12109.0 12164.8 12583.6 10273.7 10277.2 10371.8 10699.6 10842.7 10143.2 10162.4 10166.1 10248.6 10355.2 10691.1 10769.4 10774.5 10793.2 11169.6 6611.9 7009.7 7058.8 7080.3 8260.5 8729.6 10375.7 10488.3 10536.7 10882.4 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#3] mootools.html Cycles:180 Average:4789.18 Median:5323.61 stddev:2630.35 (49.4%) stddev-sans-first:2623.75 05:14:17 INFO - PROCESS | 30908 | Values: 1180.9 1342.0 1347.8 1377.4 1381.9 6010.2 7147.9 7346.5 7357.0 7570.2 7393.3 7471.3 7473.5 7486.9 7487.0 2038.2 2078.3 2079.1 2080.5 2081.6 1774.8 1831.3 2089.3 2159.0 2167.4 2103.3 2106.9 2107.8 2111.1 2114.4 2053.9 2082.4 2084.1 2085.2 2087.5 2020.4 2043.9 2079.3 2079.9 2081.2 7400.1 7494.8 7497.3 7505.5 7512.8 4438.6 4572.5 4579.5 4581.1 4585.2 2705.4 2835.6 2847.9 2887.8 2898.4 1148.7 1165.2 1689.7 1728.4 1760.0 9810.1 9922.6 9938.2 9939.7 9962.3 3938.4 3997.5 4114.1 4116.7 4182.7 7994.0 8051.3 8065.8 8067.5 8072.6 4515.4 4516.2 4521.0 4528.1 4530.0 4026.5 4158.0 4213.6 4215.9 4217.0 2904.1 2969.0 2969.4 2988.2 2993.1 902.5 956.1 986.3 992.6 994.7 771.3 788.5 790.1 796.6 799.3 4626.4 4719.7 4754.2 4768.7 4836.4 849.0 862.7 876.6 891.2 910.0 3144.0 3190.8 3237.6 3286.2 3297.1 8626.6 8947.5 8947.9 8961.7 8966.3 8778.4 8884.7 8885.4 8898.5 8908.2 6926.2 7006.4 7015.2 7015.8 7026.2 4617.7 4706.7 4794.3 4810.5 4812.4 7704.3 8004.6 8094.0 8137.2 8140.1 8057.5 8217.2 8297.5 8303.9 8435.2 6324.7 6454.2 6472.6 6476.4 6478.0 6937.6 6951.2 6968.0 6968.4 7037.7 6252.2 6303.8 6313.4 6362.3 6420.6 6172.4 6203.1 6319.4 6350.0 6445.6 5287.0 5360.2 5362.4 5412.4 5427.6 4188.6 4195.1 4278.5 4300.2 4432.2 3984.9 4035.2 4069.4 4091.7 4191.4 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#4] prototype.html Cycles:180 Average:4150.01 Median:4722.86 stddev:1182.61 (25.0%) stddev-sans-first:1150.58 05:14:17 INFO - PROCESS | 30908 | Values: 326.2 342.6 342.6 355.1 373.6 4718.8 4807.3 4812.3 4817.4 4819.0 4733.2 4739.3 4745.5 4756.4 4764.5 3590.7 3709.7 3725.7 3736.4 3834.9 3724.7 3725.8 3735.1 3750.2 3751.3 3709.7 3716.3 3718.1 3723.6 3726.9 3735.7 3740.8 3740.9 3749.9 3763.9 3131.9 3258.1 3258.9 3261.5 3281.3 4623.3 4625.7 4626.1 4627.3 4632.3 4229.9 4242.9 4251.9 4252.4 4260.8 4777.0 4925.7 4985.8 4989.6 4994.4 3595.4 3596.0 3596.4 3602.9 3614.4 5003.9 5147.9 5241.6 5270.7 5275.4 3515.6 3526.5 3554.1 3558.1 3683.1 3720.5 3734.6 3736.0 3736.5 3752.0 4243.3 4249.5 4251.3 4252.2 4260.0 4886.8 5014.3 5016.4 5063.8 5072.3 1335.1 1338.0 1351.3 1355.5 1363.6 943.7 982.0 982.8 986.2 995.3 4310.9 4326.8 4342.3 4351.4 4359.9 4998.3 5005.8 5049.7 5051.4 5079.7 4173.7 4332.4 4339.1 4346.9 4347.5 2656.7 2659.7 2672.5 2700.9 2708.4 4614.2 4690.8 4697.4 4708.3 4726.9 5005.3 5103.1 5202.3 5222.7 5234.3 4882.8 4894.1 4895.7 4911.0 4921.9 5140.9 5157.4 5182.7 5188.9 5189.2 4828.0 5025.6 5040.9 5053.4 5074.5 4647.4 4770.2 4850.4 4866.9 4890.7 3617.9 3676.5 3697.6 3708.6 3719.2 5158.6 5188.1 5209.2 5210.3 5216.5 4892.3 4976.7 4997.3 5007.1 5057.8 4934.8 4995.7 4998.2 5007.3 5028.2 4947.7 4950.7 4981.5 4992.7 4994.3 4825.0 5024.1 5039.4 5045.7 5087.6 4926.4 4927.4 4937.7 4938.3 4947.7 05:14:17 INFO - PROCESS | 30908 | 05:14:17 INFO - PROCESS | 30908 | [#5] yui.html Cycles:180 Average:14219.54 Median:25532.99 stddev:17652.71 (69.1%) stddev-sans-first:17672.78 05:14:17 INFO - PROCESS | 30908 | Values: 642.0 657.0 714.1 734.6 750.4 12262.0 13138.4 13159.6 13203.8 13290.6 13048.8 13224.1 13228.3 13251.6 13253.9 805.4 828.6 829.5 829.6 836.5 832.1 834.7 835.0 835.5 835.7 870.6 871.1 871.3 871.6 872.2 863.7 865.1 865.5 865.9 866.9 637.3 638.7 638.8 639.0 639.5 13097.6 13534.9 14053.9 14089.4 14092.6 13048.9 13568.0 13583.8 13661.5 13691.5 10162.5 10332.8 10451.5 10646.6 10705.3 3175.1 3194.0 3222.3 3228.6 3235.9 58767.0 59016.7 60729.4 61685.7 62009.4 9100.2 9259.5 9272.9 9321.8 9706.5 17247.9 17293.7 17297.7 17300.5 17327.2 6152.7 6292.7 6488.1 6544.6 6551.3 3437.1 3491.8 3619.7 3622.0 3623.5 3003.8 3079.5 3083.2 3089.0 3089.2 474.8 477.9 482.2 482.2 482.2 516.4 521.4 525.3 535.7 545.8 7969.6 8423.7 8968.7 9060.0 9246.6 478.5 497.8 502.2 507.2 520.3 4070.4 4150.3 4265.4 4289.1 4333.5 51389.9 51685.5 65134.7 65232.0 67757.7 55099.7 55418.4 55798.2 56250.0 56652.0 50563.7 52038.7 52463.0 52545.5 53166.7 7954.5 8222.4 8242.3 8352.2 8404.5 35513.0 35692.5 36621.8 36842.6 37401.8 34697.5 42723.6 43022.6 44247.0 44376.8 9849.6 10045.0 10193.9 10223.5 10451.8 10695.5 10718.2 10984.9 11028.6 11177.9 9863.5 10387.6 10388.6 10431.6 10797.4 10808.6 10964.4 11015.9 11087.3 11114.9 10412.3 10568.3 10633.5 10649.3 10654.6 8605.8 8763.4 8892.5 9158.8 9298.5 8316.3 8414.9 8840.7 9100.0 9121.2 05:14:17 INFO - PROCESS | 30908 | -------- Summary: end -------- 05:14:17 INFO - PROCESS | 30908 | 05:14:18 INFO - TEST-INFO | 30908: exit 0 05:14:18 INFO - TEST-OK | dromaeo_css | took 1125667ms 05:14:18 INFO - TEST-START | kraken 05:14:18 INFO - operating with platform_type : linux_ 05:14:18 INFO - Initialising browser for kraken test... 05:14:18 INFO - TEST-INFO | started process 31221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYw2JRQ/profile http://localhost:52630/getInfo.html) 05:14:22 INFO - PROCESS | 31221 | Iterating over profile /tmp/tmpYw2JRQ/profile 05:14:23 INFO - PROCESS | 31221 | __metrics Screen width/height:1600/1200 05:14:23 INFO - PROCESS | 31221 | colorDepth:24 05:14:23 INFO - PROCESS | 31221 | Browser inner width/height: 1024/697 05:14:23 INFO - PROCESS | 31221 | __metrics 05:14:23 INFO - PROCESS | 31221 | Stat failure 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/frequencyCap.json = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/extensions.ini = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/bookmarkbackups = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/blocklist.xml = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/key3.db = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/cookies.sqlite = Fri Feb 17 2017 05:14:22 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/safebrowsing = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/extensions.json = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/places.sqlite-wal = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/cert8.db = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/directoryLinks.json = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/mimeTypes.rdf = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/cache2 = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/user.js = Fri Feb 17 2017 05:14:18 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/pluginreg.dat = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/crashes = Fri Feb 17 2017 05:14:18 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | Stat failure 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/permissions.sqlite = Fri Feb 17 2017 05:14:20 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/compatibility.ini = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/gmp = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/addons.json = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/localstore.rdf = Fri Feb 17 2017 04:54:52 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/.parentlock = Fri Feb 17 2017 05:14:18 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/thumbnails = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/places.sqlite = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/secmod.db = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/minidumps = Fri Feb 17 2017 05:14:18 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/content-prefs.sqlite = Fri Feb 17 2017 05:14:21 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/places.sqlite-shm = Fri Feb 17 2017 05:14:20 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/prefs.js = Fri Feb 17 2017 04:54:52 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/startupCache = Fri Feb 17 2017 05:14:18 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/sessionCheckpoints.json = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/extensions = Fri Feb 17 2017 05:14:19 GMT-0800 (PST) 05:14:23 INFO - PROCESS | 31221 | No birth date. Using mtime. 05:14:23 INFO - PROCESS | 31221 | Using date: /tmp/tmpYw2JRQ/profile/webappsstore.sqlite = Fri Feb 17 2017 05:14:23 GMT-0800 (PST) 05:14:24 INFO - PROCESS | 31221 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:14:24 INFO - PROCESS | 31221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:14:24 INFO - PROCESS | 31221 | console.error: PushService: 05:14:24 INFO - PROCESS | 31221 | stateChangeProcessEnqueue: Error transitioning state 05:14:24 INFO - PROCESS | 31221 | UnknownError 05:14:25 INFO - TEST-INFO | 31221: exit 0 05:14:25 INFO - Browser initialized. 05:14:25 INFO - Running cycle 1/1 for kraken test... 05:14:25 INFO - TEST-INFO | started process 31313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYw2JRQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:37 INFO - PROCESS | 31313 | RSS: Main: 147316736 05:14:37 INFO - PROCESS | 31313 | 05:14:40 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-beat-detection) 05:14:41 INFO - PROCESS | 31313 | RSS: Main: 201662464 05:14:41 INFO - PROCESS | 31313 | 05:14:44 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-dft) 05:14:44 INFO - PROCESS | 31313 | RSS: Main: 181805056 05:14:44 INFO - PROCESS | 31313 | 05:14:47 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-fft) 05:14:48 INFO - PROCESS | 31313 | RSS: Main: 263999488 05:14:48 INFO - PROCESS | 31313 | 05:14:50 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-oscillator) 05:14:51 INFO - PROCESS | 31313 | RSS: Main: 174026752 05:14:51 INFO - PROCESS | 31313 | 05:14:53 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:14:54 INFO - PROCESS | 31313 | RSS: Main: 178151424 05:14:54 INFO - PROCESS | 31313 | 05:14:57 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-darkroom) 05:14:57 INFO - PROCESS | 31313 | RSS: Main: 217346048 05:14:57 INFO - PROCESS | 31313 | 05:15:01 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-desaturate) 05:15:01 INFO - PROCESS | 31313 | RSS: Main: 211374080 05:15:01 INFO - PROCESS | 31313 | 05:15:04 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52630/tests/kraken/driver.html?testName=json-parse-financial) 05:15:05 INFO - PROCESS | 31313 | RSS: Main: 213499904 05:15:05 INFO - PROCESS | 31313 | 05:15:06 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52630/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:15:07 INFO - PROCESS | 31313 | RSS: Main: 176689152 05:15:07 INFO - PROCESS | 31313 | 05:15:09 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:15:10 INFO - PROCESS | 31313 | RSS: Main: 184950784 05:15:10 INFO - PROCESS | 31313 | 05:15:12 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:15:12 INFO - PROCESS | 31313 | RSS: Main: 193036288 05:15:12 INFO - PROCESS | 31313 | 05:15:15 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:15:16 INFO - PROCESS | 31313 | RSS: Main: 201826304 05:15:16 INFO - PROCESS | 31313 | 05:15:19 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:15:20 INFO - PROCESS | 31313 | RSS: Main: 197836800 05:15:20 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52630/tests/kraken/driver.html?testName=ai-astar) 05:15:22 INFO - PROCESS | 31313 | RSS: Main: 250978304 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | __start_tp_report 05:15:22 INFO - PROCESS | 31313 | _x_x_mozilla_page_load 05:15:22 INFO - PROCESS | 31313 | _x_x_mozilla_page_load_details 05:15:22 INFO - PROCESS | 31313 | |i|pagename|runs| 05:15:22 INFO - PROCESS | 31313 | |0;ai-astar;115;117;116;118;116;115;115;115;115;115 05:15:22 INFO - PROCESS | 31313 | |1;audio-beat-detection;155;126;126;165;128;127;130;126;164;126 05:15:22 INFO - PROCESS | 31313 | |2;audio-dft;163;163;162;163;163;164;163;162;162;162 05:15:22 INFO - PROCESS | 31313 | |3;audio-fft;77;77;77;116;77;77;77;77;92;76 05:15:22 INFO - PROCESS | 31313 | |4;audio-oscillator;84;85;108;85;86;85;100;85;85;110 05:15:22 INFO - PROCESS | 31313 | |5;imaging-gaussian-blur;114;113;113;113;113;113;113;113;113;113 05:15:22 INFO - PROCESS | 31313 | |6;imaging-darkroom;149;148;150;150;150;148;149;148;149;149 05:15:22 INFO - PROCESS | 31313 | |7;imaging-desaturate;95;97;96;96;96;95;95;95;95;95 05:15:22 INFO - PROCESS | 31313 | |8;json-parse-financial;61;61;61;62;62;68;62;60;62;62 05:15:22 INFO - PROCESS | 31313 | |9;json-stringify-tinderbox;67;67;66;66;67;67;67;67;66;68 05:15:22 INFO - PROCESS | 31313 | |10;stanford-crypto-aes;87;89;88;87;90;88;87;88;88;92 05:15:22 INFO - PROCESS | 31313 | |11;stanford-crypto-ccm;139;152;148;152;136;142;136;137;183;165 05:15:22 INFO - PROCESS | 31313 | |12;stanford-crypto-pbkdf2;186;183;186;192;186;184;182;183;182;182 05:15:22 INFO - PROCESS | 31313 | |13;stanford-crypto-sha256-iterative;70;71;75;72;74;68;70;70;69;68 05:15:22 INFO - PROCESS | 31313 | __end_tp_report 05:15:22 INFO - PROCESS | 31313 | __start_cc_report 05:15:22 INFO - PROCESS | 31313 | _x_x_mozilla_cycle_collect,2048 05:15:22 INFO - PROCESS | 31313 | __end_cc_report 05:15:22 INFO - PROCESS | 31313 | __startTimestamp1487337322389__endTimestamp 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | ------- Summary: start ------- 05:15:22 INFO - PROCESS | 31313 | Number of tests: 14 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#0] ai-astar Cycles:10 Average:115.70 Median:115.50 stddev:1.06 (0.9%) stddev-sans-first:1.09 05:15:22 INFO - PROCESS | 31313 | Values: 115.0 117.0 116.0 118.0 116.0 115.0 115.0 115.0 115.0 115.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:129.00 stddev:16.83 (13.0%) stddev-sans-first:16.59 05:15:22 INFO - PROCESS | 31313 | Values: 155.0 126.0 126.0 165.0 128.0 127.0 130.0 126.0 164.0 126.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 05:15:22 INFO - PROCESS | 31313 | Values: 163.0 163.0 162.0 163.0 163.0 164.0 163.0 162.0 162.0 162.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#3] audio-fft Cycles:10 Average:82.30 Median:77.00 stddev:12.76 (16.6%) stddev-sans-first:13.39 05:15:22 INFO - PROCESS | 31313 | Values: 77.0 77.0 77.0 116.0 77.0 77.0 77.0 77.0 92.0 76.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:85.00 stddev:10.46 (12.3%) stddev-sans-first:10.75 05:15:22 INFO - PROCESS | 31313 | Values: 84.0 85.0 108.0 85.0 86.0 85.0 100.0 85.0 85.0 110.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 05:15:22 INFO - PROCESS | 31313 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 05:15:22 INFO - PROCESS | 31313 | Values: 149.0 148.0 150.0 150.0 150.0 148.0 149.0 148.0 149.0 149.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 05:15:22 INFO - PROCESS | 31313 | Values: 95.0 97.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:2.18 (3.5%) stddev-sans-first:2.28 05:15:22 INFO - PROCESS | 31313 | Values: 61.0 61.0 61.0 62.0 62.0 68.0 62.0 60.0 62.0 62.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 05:15:22 INFO - PROCESS | 31313 | Values: 67.0 67.0 66.0 66.0 67.0 67.0 67.0 67.0 66.0 68.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:88.00 stddev:1.58 (1.8%) stddev-sans-first:1.59 05:15:22 INFO - PROCESS | 31313 | Values: 87.0 89.0 88.0 87.0 90.0 88.0 87.0 88.0 88.0 92.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:150.00 stddev:15.14 (10.1%) stddev-sans-first:15.62 05:15:22 INFO - PROCESS | 31313 | Values: 139.0 152.0 148.0 152.0 136.0 142.0 136.0 137.0 183.0 165.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:185.00 stddev:3.10 (1.7%) stddev-sans-first:3.24 05:15:22 INFO - PROCESS | 31313 | Values: 186.0 183.0 186.0 192.0 186.0 184.0 182.0 183.0 182.0 182.0 05:15:22 INFO - PROCESS | 31313 | 05:15:22 INFO - PROCESS | 31313 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:70.50 stddev:2.36 (3.3%) stddev-sans-first:2.49 05:15:22 INFO - PROCESS | 31313 | Values: 70.0 71.0 75.0 72.0 74.0 68.0 70.0 70.0 69.0 68.0 05:15:22 INFO - PROCESS | 31313 | -------- Summary: end -------- 05:15:22 INFO - PROCESS | 31313 | 05:15:23 INFO - TEST-INFO | 31313: exit 0 05:15:23 INFO - TEST-OK | kraken | took 64953ms 05:15:23 INFO - SUITE-END | took 1190s 05:15:24 INFO - Completed test suite (00:19:50) 05:15:24 INFO - javascript benchmark 05:15:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6170.317407094589, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2588.6893572771264, 2604.3489127718067, 2633.9736602633966, 2637.8285411448264, 2664.9600255996165, 4609.377734005908, 4638.0491999140095, 4720.457147428043, 4743.169945259574, 4796.928046079307, 4566.063956888838, 4596.563326483979, 4640.4431468223775, 4652.6975746576445, 4683.672142949995, 4040.272750904836, 4221.345691417828, 4437.955620443802, 4438.600525952664, 4459.0, 4184.832606695713, 4353.0423306872435, 4391.5827996340295, 4456.55434456555, 4481.820727170909, 4202.873913782591, 4215.0, 4242.745435273893, 4246.044639956016, 4254.0, 4243.554426785205, 4244.639205667522, 4276.2089013532595, 4298.441202643668, 4344.63070638996, 4288.549702281247, 4310.52584215736, 4346.195953748566, 4352.173087113439, 4367.192069467159, 4696.154692155411, 4707.105649926537, 4710.8351207707565, 4721.834735784231, 4732.550407711262, 4852.902941941142, 5015.774290156952, 5017.37282839645, 5026.47222041684, 5037.647364684473, 4908.754562271872, 4953.6037117030555, 4973.925391119136, 4977.328060711794, 4983.750812459399, 4599.35428517578, 4634.096351211549, 4648.140094082605, 4654.697444666086, 4663.603593694506, 5081.161608334583, 5090.618203634742, 5102.744862756847, 5105.030044291572, 5105.106606343875, 5014.874628134326, 5028.019536190408, 5081.4918508148885, 5094.337736094284, 5098.056859481007, 5121.411037730709, 5123.641345105807, 5125.64120511568, 5130.4356520782685, 5177.326947496801, 4892.48628893961, 5085.923711144335, 5095.668781529189, 5098.694078355311, 5103.0, 4896.95103048972, 4955.702657840541, 4965.379327584052, 4975.850724478271, 5020.297158397827, 4324.308110702273, 4385.079133382025, 4393.406890069864, 4393.802278897458, 4396.054848207651, 3776.6034566370136, 3780.8676696315497, 3780.9243815123546, 3811.5807261201244, 3820.388737801994, 4456.9331460028125, 4466.793965629262, 4469.240229161051, 4490.461144662596, 4505.211587972091, 4931.778069986859, 4933.802647894116, 4954.281629163751, 5017.648764586444, 5123.308353372247, 4313.784310784448, 4364.62900653448, 4369.169857727022, 4372.23485889968, 4374.321980093091, 4471.5081341052455, 4501.0547784964865, 4513.887152821206, 4548.090381923628, 4561.703489273186, 4736.123817093849, 4741.5495527924795, 4746.644001699818, 4747.264174053027, 4782.665213435026, 4884.291777692144, 4920.507949205051, 4922.581580565548, 4995.625328100335, 5044.571211447068, 4696.906061878812, 4712.293156026623, 4713.057388522241, 4717.787699553597, 4718.693284936468, 4898.9020219594695, 4904.754762261801, 4905.23968784839, 4908.092002979461, 4911.803527858846, 4973.5523802857915, 4973.875653108701, 4977.825776097964, 4978.925316120189, 5090.669106508065, 4983.5763960063805, 5036.445990941067, 5047.369078865142, 5066.544011039025, 5069.0, 5030.622703297194, 5044.319016932667, 5053.873653158554, 5055.721935293447, 5069.847904562869, 4802.7838747255755, 4961.479044700399, 4967.254911763264, 4973.229149481836, 5021.49785021495, 4918.114739346952, 4972.726500042532, 4976.452590215001, 4976.975115124401, 4977.800887964441, 4868.464468908488, 4870.05034018363, 4870.9269360960325, 4876.341693871282, 4881.31661567388], "value": 4630.518228495672, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.8796258804356, 568.573569822633, 573.406524247404, 596.3916804859041, 598.5570677698504, 26604.334891627695, 28591.714082859166, 36879.0, 39067.21865562687, 39348.0, 38749.41875871864, 38780.41829372562, 38785.0, 38875.80562097193, 38896.41655375172, 7383.150937642173, 7553.244675532458, 7556.773296801105, 7570.6909392635225, 7645.694172233104, 6747.696353664097, 6930.757423490179, 9425.575849086774, 9590.376625519319, 9632.988536203702, 9534.189593884528, 9579.80840383192, 9582.664606738765, 9583.28125390595, 9588.664396746115, 9480.762980925463, 9530.285228607881, 9537.666181683677, 9544.0, 9557.092076252746, 9360.391574547633, 9362.0, 9397.295202859745, 9423.858642120374, 9444.433333999914, 34230.48654270188, 34560.13599660004, 34589.82705086459, 34603.4809477858, 34667.13332166691, 3444.48332750087, 3498.1079824644553, 3505.964940350589, 3516.1209697575605, 3559.661832125944, 23829.0, 24849.0, 25315.0, 25442.236732898044, 26872.59691104635, 3270.460374038281, 3376.1929481292136, 3487.459443786192, 3519.665631764978, 3628.1655219299446, 230648.0, 233095.0, 233735.0, 234255.0, 236054.819725902, 38929.0, 45113.32330014987, 45113.548864510936, 45231.54768452339, 45413.772931135136, 38625.42061869074, 56263.71868140715, 61703.38296617066, 62861.0, 66854.33145668579, 10884.346939183675, 12023.51318117736, 12117.151799374136, 12134.211276267015, 12395.070369722307, 8072.354211663171, 9015.819683606422, 9266.860997085048, 9270.119338662817, 9436.811263774685, 18380.264789408542, 18536.44390668282, 18557.814421855608, 18613.81386186148, 18638.720419193724, 7086.610236437045, 7195.89091744867, 7254.709811607477, 7268.382187514015, 7376.819708846559, 746.1046743907225, 748.221849276757, 749.7900587835414, 765.3226894198674, 765.3685709289747, 26108.216753497047, 27057.0, 27406.862965685043, 27584.862075689896, 27614.309642258704, 861.2468709023657, 862.4394143806576, 863.6070587882498, 872.1888643561422, 1031.6750223679517, 13899.79150312746, 14261.215633140277, 14308.354539227914, 14459.349329280207, 14503.0, 129064.35467822789, 129163.35418322848, 129629.0, 129959.35020325027, 130208.0, 48158.75920620444, 48722.0, 48770.75614621904, 48876.266855997186, 48906.75546622244, 1138.9145814063813, 1160.1356989043047, 1162.7151347919475, 1174.6358628825092, 1190.0301254477747, 24440.0, 25398.365040874127, 26173.476530468903, 26404.471910561297, 26445.33886652849, 40520.18959620732, 40801.59198407978, 40821.38767918424, 40922.386164208154, 40970.59029409668, 268985.0, 269386.0, 269765.0, 270042.0, 290885.0, 26596.734032659424, 34552.0, 35558.111047224025, 35690.64309356873, 37247.0, 35314.82342588271, 35722.10694732548, 36791.63208367882, 38761.418578720775, 39522.2095558093, 30111.698883011766, 30120.0, 30745.53881691821, 30926.690733092382, 31789.8410507946, 30826.383472330876, 30870.53694194634, 30870.845645771627, 30943.845280773454, 32020.83989580037, 29145.562816557343, 30008.69991300059, 30130.24674383158, 30320.39359212759, 30603.54094688626, 26195.73804261933, 27763.44473110486, 27804.58293125564, 27817.582736259377, 27868.581971270854, 22018.44953876166, 22056.669149962443, 22101.889490552443, 22187.77812221857, 22220.888895555418], "value": 16328.865511543145, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.62165415486686, 383.22205921978394, 388.718179319993, 389.57730862014705, 421.72166370195674, 12404.255744655316, 12743.426545805438, 13359.19844809311, 17705.822941770577, 18405.26378944844, 18045.63908721825, 18131.546711332223, 18227.72658410125, 18308.633827323447, 18362.1737021834, 5099.107656160167, 5271.815486457975, 5304.124819404794, 5305.0, 5306.389765176996, 4948.628852836034, 5026.095302845486, 5072.4166720827025, 5176.948230517684, 5251.711155886424, 5046.54581087702, 5046.747662616873, 5047.520485553885, 5049.065922804293, 5049.24261360797, 4869.196582563873, 4957.5786058185095, 5043.39479262489, 5047.041062198171, 5054.5198206170535, 4567.0409214065085, 4574.016586433932, 4578.816847326103, 4580.793864276082, 4589.495155532889, 19650.606987860305, 20348.898255508775, 20454.181832726674, 21590.460238494004, 21592.35222943314, 2153.9461513462134, 2203.2839327218558, 2209.7348318201757, 2221.589006033889, 2275.5448910218024, 9613.182879455255, 9750.853737193947, 9888.159506441962, 11111.555537778479, 12380.938095309555, 1606.775051492792, 1727.5594723345591, 1745.895246285227, 1843.0647819789301, 1875.9906200469047, 59602.99999999956, 61724.382756172316, 67555.0, 73597.63201184013, 77650.0, 9170.49562274068, 9344.972053074156, 9637.542426681151, 9722.9027709722, 9773.169280611228, 13209.73580528384, 13211.537596184087, 13226.206427614374, 13262.27057511846, 13357.065005449525, 6310.1796766420075, 6443.710033048524, 6501.934980650133, 6680.432163266079, 6681.532292739462, 5799.942000580025, 5805.10020946754, 5926.1999630049395, 6024.3975602439405, 6025.758969641165, 3986.760794352348, 4031.07285324377, 4056.3712624542654, 4236.745795252295, 4426.690131690815, 1845.8154184581704, 1851.176591057807, 1853.981460185408, 1893.7064754963003, 1899.2329253573355, 747.9626018699153, 773.6750564762813, 775.4455564271573, 781.6716978868889, 794.5232860283899, 16439.589010274598, 16845.0, 17395.304187832597, 17527.474175774747, 17843.0, 758.0372926383573, 759.4266328921628, 765.9923400766032, 777.7472321495536, 786.469133335008, 8691.826163476695, 9680.903190968, 9723.854142187873, 9735.123838854537, 9826.263030272758, 440573.0, 446698.0, 452388.0, 457967.0, 459365.703171482, 81989.0, 84466.57766711127, 88292.5585372069, 89441.10558894459, 90037.54981225052, 878.7495563764296, 888.8888888888889, 908.4049947284672, 909.3907082254773, 910.1308250620641, 14532.927335363256, 14838.554843354284, 15190.544283671508, 15207.163606001333, 15330.38678452894, 27961.720382795913, 28703.569446458736, 30801.0, 36125.81937090298, 38402.61597383991, 47426.52573474359, 49484.25773613471, 50056.749716251186, 50651.746741266055, 53145.0, 5563.7774489019985, 7008.983697363956, 7436.96281518589, 7480.850382992201, 7799.9220007799195, 8925.509096999727, 11956.641300760992, 12109.031277497956, 12164.75670486603, 12583.622491325275, 10273.743156420851, 10277.177825773771, 10371.792564148524, 10699.572017119539, 10842.674719758419, 10143.188544916537, 10162.441065741456, 10166.085052345325, 10248.590056397661, 10355.223358248011, 10691.144708423151, 10769.353838769699, 10774.515146818569, 10793.244472886823, 11169.609063682732, 6611.900821487778, 7009.719611215494, 7058.752943646948, 7080.2919708028785, 8260.545669988209, 8729.607167677343, 10375.740606484898, 10488.26582139243, 10536.736581585521, 10882.40146791934], "value": 8058.016059612197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1180.893719565239, 1341.979870301945, 1347.791092380681, 1377.3732951507066, 1381.868722471365, 6010.158577799111, 7147.927101140669, 7346.522476039054, 7357.043584334043, 7570.242975702427, 7393.260673932604, 7471.252874712526, 7473.476856620039, 7486.925130748691, 7486.962565187167, 2038.1745393115775, 2078.272604588397, 2079.07481170879, 2080.4694802825334, 2081.5524662197627, 1774.8402643762058, 1831.313257528427, 2089.268755935426, 2158.956820863582, 2167.371462275938, 2103.3374487036613, 2106.8630539014994, 2107.8419118566094, 2111.144986280559, 2114.397396741929, 2053.9486512837148, 2082.3961051295105, 2084.145500344859, 2085.1971990783504, 2087.499000239947, 2020.3534868841975, 2043.8569300148995, 2079.3034333498294, 2079.864808787439, 2081.2091405266056, 7400.148982866932, 7494.775156745306, 7497.250274972514, 7505.512141710826, 7512.774616761506, 4438.644908407319, 4572.474165470947, 4579.4504659440745, 4581.083783243364, 4585.197590421662, 2705.4318593095472, 2835.5888396182436, 2847.857607119656, 2887.754540864029, 2898.449294634013, 1148.7300484386246, 1165.1669056624555, 1689.7380905959474, 1728.438257566296, 1760.0, 9810.117089461983, 9922.553485093189, 9938.204943604494, 9939.65211217611, 9962.302638815287, 3938.3895496198134, 3997.4603428537357, 4114.0948991221885, 4116.732412393185, 4182.665386769051, 7993.960030199812, 8051.275385215359, 8065.758027259191, 8067.515949042959, 8072.636731347104, 4515.412996310524, 4516.23224051912, 4520.9827788747925, 4528.0943811237885, 4529.9547004530195, 4026.510191229264, 4157.97921010399, 4213.578642135823, 4215.873523794291, 4216.978915105405, 2904.1142451552478, 2968.985155074211, 2969.3615872587065, 2988.237999310162, 2993.1469531183507, 902.5442151713474, 956.1442508954447, 986.329296078659, 992.5880759484877, 994.6866736977829, 771.2750014985895, 788.5150632361057, 790.1229635105029, 796.5579103597444, 799.3046049936593, 4626.421697287839, 4719.663766435568, 4754.168020596382, 4768.666193366431, 4836.371271734652, 849.0405841399179, 862.6714859117016, 876.574861192334, 891.1667590802621, 909.9636014559344, 3143.9528407073913, 3190.760692948038, 3237.627672817609, 3286.2277364819247, 3297.1427428868665, 8626.568671566396, 8947.507887066275, 8947.865782013276, 8961.686340978034, 8966.327525435618, 8778.429402088845, 8884.73345799627, 8885.378023538291, 8898.4660920345, 8908.153725396078, 6926.238113807578, 7006.369426751617, 7015.193252775996, 7015.824604385033, 7026.191987921352, 4617.722936623813, 4706.694064885772, 4794.328793968917, 4810.494898035796, 4812.374391329245, 7704.345130663956, 8004.559749213616, 8094.028716554051, 8137.186281371815, 8140.104588495379, 8057.476264042818, 8217.178282171735, 8297.502149870785, 8303.916960830556, 8435.24082832548, 6324.715387807468, 6454.1609590752905, 6472.644004579605, 6476.352364763486, 6478.0, 6937.583744975318, 6951.2353641098425, 6967.965160174167, 6968.407685346802, 7037.718491260497, 6252.155958945484, 6303.842403939938, 6313.40022697843, 6362.3319551448285, 6420.6468644225015, 6172.382761723792, 6203.069539569101, 6319.399657032574, 6349.968250158904, 6445.6454894981225, 5287.048331300402, 5360.16917377807, 5362.410134885237, 5412.404635490014, 5427.5657947363325, 4188.5811418857875, 4195.077083041726, 4278.486581610102, 4300.16146851361, 4432.202203603382, 3984.9203015940097, 4035.172789578071, 4069.3895915612893, 4091.734037287567, 4191.392248124066], "value": 3909.2007573670153, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.1666442240077, 342.5598106433234, 342.6265370745885, 355.1210753385371, 373.60771190250233, 4718.787654555545, 4807.302941073543, 4812.326274321589, 4817.445993710724, 4818.975905120469, 4733.171694953387, 4739.284412010054, 4745.454272758634, 4756.405449318835, 4764.5235476452335, 3590.658887405705, 3709.684676802475, 3725.701943844486, 3736.4021756518964, 3834.8657796977113, 3724.7020238381, 3725.776453412804, 3735.1409175889426, 3750.2312026034597, 3751.287255421475, 3709.666130048295, 3716.256748650267, 3718.0998820919726, 3723.609021052791, 3726.9252866385204, 3735.701143908481, 3740.775553466787, 3740.8877733668032, 3749.850005999757, 3763.8682646107254, 3131.937361252785, 3258.120307516957, 3258.885938992124, 3261.5433839262523, 3281.343731253759, 4623.329617205486, 4625.722456652612, 4626.1441633297945, 4627.282771170474, 4632.2819962905805, 4229.900225941277, 4242.896846819818, 4251.893702657459, 4252.4046633471335, 4260.786960651986, 4776.9697878401885, 4925.679830810986, 4985.825496107655, 4989.55094041538, 4994.350734404504, 3595.388783906742, 3595.982020089909, 3596.3886139356373, 3602.9279414411826, 3614.4397618369194, 5003.924941125886, 5147.922781158286, 5241.580673546107, 5270.6837589744355, 5275.446078161776, 3515.6132825389186, 3526.523919270917, 3554.085649767166, 3558.146044949245, 3683.1344634010984, 3720.479132921393, 3734.6452087051684, 3735.9813200933822, 3736.5142531470733, 3751.9812400938367, 4243.27864263076, 4249.532551419341, 4251.341042138473, 4252.192083504125, 4260.0, 4886.755662216875, 5014.348134742533, 5016.398032236155, 5063.797448102108, 5072.289879416885, 1335.0721248732038, 1337.9898176876395, 1351.3378444562093, 1355.5120156743565, 1363.5909227231791, 943.7020854219996, 982.0, 982.7739619887466, 986.1814693804125, 995.2983146881431, 4310.892227694333, 4326.841405773803, 4342.261815491374, 4351.36905148758, 4359.888228501712, 4998.300917504507, 5005.7997680092385, 5049.671771334852, 5051.368578927634, 5079.720615366116, 4173.7495750254475, 4332.393464914914, 4339.132173565299, 4346.869593912188, 4347.543507931685, 2656.7343265673667, 2659.6808382994163, 2672.50558646651, 2700.8919643214444, 2708.404151086758, 4614.169449499055, 4690.771883355447, 4697.389339385926, 4708.270218116198, 4726.929096063562, 5005.299258103868, 5103.050041459778, 5202.297689811902, 5222.686638801684, 5234.26720259164, 4882.829100981519, 4894.143524883202, 4895.747955940236, 4911.0, 4921.8523444296725, 5140.948590514047, 5157.355330583677, 5182.714950677749, 5188.870278242923, 5189.247559103985, 4827.972251654383, 5025.597952163891, 5040.924386134137, 5053.408176424415, 5074.517920797518, 4647.372604698322, 4770.236762118045, 4850.417949846041, 4866.9269960949905, 4890.706557606555, 3617.855285788539, 3676.4852920591675, 3697.6487233712755, 3708.64501041835, 3719.237556301006, 5158.638895277295, 5188.068741161232, 5209.192575150858, 5210.322658054429, 5216.452272511332, 4892.31507588945, 4976.651634385558, 4997.250412438163, 5007.073691366963, 5057.848264552069, 4934.777934992862, 4995.675281106862, 4998.2002879539095, 5007.271037284616, 5028.220625803006, 4947.727874966768, 4950.702957822542, 4981.476944920876, 4992.700437973734, 4994.300797888372, 4825.0, 5024.120778863758, 5039.420466646383, 5045.747712614428, 5087.592992560661, 4926.374296482224, 4927.38278362674, 4937.679050861682, 4938.259261110862, 4947.678400903929], "value": 3798.5775030278555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.9807405777829, 656.9934300656995, 714.1216303946146, 734.6473692627538, 750.4296734481794, 12262.0, 13138.408771605276, 13159.60521184366, 13203.801942970851, 13290.601281961544, 13048.804267935988, 13224.074314797968, 13228.338583070856, 13251.602451926434, 13253.867461325408, 805.4321703199231, 828.6478246745139, 829.5396055189367, 829.6142293833366, 836.456303402787, 832.063928080909, 834.7120243515977, 834.9749507514784, 835.4820011592791, 835.678263868412, 870.5995242188601, 871.1158174452904, 871.2898987325341, 871.6339137562207, 872.2193636695154, 863.7451951674271, 865.1348651348651, 865.4677373415336, 865.8831057807209, 866.9046404895456, 637.2512298049772, 638.7093872288098, 638.8179368879847, 638.955273130881, 639.5299454900644, 13097.607071787861, 13534.93232533831, 14053.85946140546, 14089.436422543085, 14092.647683807987, 13048.86951130486, 13568.0, 13583.796243056264, 13661.521846735317, 13691.520796772165, 10162.542685579167, 10332.845007324897, 10451.477426128664, 10646.57413703451, 10705.25063245573, 3175.063356309894, 3194.0, 3222.2910959588853, 3228.6287076985977, 3235.854386552611, 58767.0, 59016.70491647557, 60729.39270607282, 61685.6915715423, 62009.37990620126, 9100.180983711498, 9259.490728009889, 9272.907270927339, 9321.813563728687, 9706.466144362128, 17247.913760431118, 17293.740593891103, 17297.740533892003, 17300.48098557045, 17327.2202750874, 6152.692365381802, 6292.653904035231, 6488.124103246001, 6544.574602650812, 6551.263915062512, 3437.089171369589, 3491.825408729553, 3619.746617736786, 3621.9818900905852, 3623.5289412376223, 3003.834789086621, 3079.5380692895797, 3083.2446050717717, 3089.0115163148025, 3089.1504836170234, 474.8124490826173, 477.87097483679213, 482.16103979076854, 482.17788670317026, 482.22121274142006, 516.4190285928331, 521.405597618715, 525.2593842681877, 535.7107162132405, 545.7680485793522, 7969.561674107979, 8423.70517031901, 8968.730938071869, 9060.003399626035, 9246.583903724348, 478.4545617995488, 497.8182963218399, 502.24161516110206, 507.1910303066603, 520.3079903728033, 4070.369092790563, 4150.335946248586, 4265.3815196796295, 4289.120730250291, 4333.501647310537, 51389.860790590785, 51685.48314516882, 65134.67432662806, 65232.0, 67757.6612116946, 55099.72450137723, 55418.44581554133, 55798.16302755462, 56250.0, 56652.0, 50563.747181264596, 52038.73980630073, 52462.95074098497, 52545.47454525406, 53166.73416632892, 7954.482958607672, 8222.424430289822, 8242.258196762321, 8352.206540378655, 8404.53775042354, 35512.962796325206, 35692.46461303031, 36621.816890915376, 36842.63157368392, 37401.81299093596, 34697.479537806445, 42723.57276427196, 43022.56977430185, 44246.98630341465, 44376.7781161105, 9849.60601575929, 10045.0, 10193.949030254802, 10223.539940702538, 10451.790964180826, 10695.538384225387, 10718.196135289729, 10984.890151098387, 11028.558857645605, 11177.944110279397, 9863.506824658882, 10387.636432724817, 10388.636397726043, 10431.634892778717, 10797.406142662228, 10808.621698240524, 10964.396958167377, 11015.889841101487, 11087.279326843729, 11114.88885111171, 10412.32319899204, 10568.260221784401, 10633.468326583485, 10649.307794993301, 10654.62708805188, 8605.82788344217, 8763.430377025474, 8892.466452012899, 9158.77103072402, 9298.488583127993, 8316.326277267171, 8414.915850841659, 8840.704718281193, 9100.0, 9121.179093881583], "value": 6099.2120721584915, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 116.0, 118.0, 116.0, 115.0, 115.0, 115.0, 115.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 126.0, 165.0, 128.0, 127.0, 130.0, 126.0, 164.0, 126.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 163.0, 163.0, 164.0, 163.0, 162.0, 162.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 116.0, 77.0, 77.0, 77.0, 77.0, 92.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 108.0, 85.0, 86.0, 85.0, 100.0, 85.0, 85.0, 110.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 150.0, 150.0, 148.0, 149.0, 148.0, 149.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 62.0, 68.0, 62.0, 60.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 66.0, 68.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 88.0, 87.0, 90.0, 88.0, 87.0, 88.0, 88.0, 92.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 148.0, 152.0, 136.0, 142.0, 136.0, 137.0, 183.0, 165.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 186.0, 192.0, 186.0, 184.0, 182.0, 183.0, 182.0, 182.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 75.0, 72.0, 74.0, 68.0, 70.0, 70.0, 69.0, 68.0], "value": 70.7, "unit": "score"}]}]} 05:15:24 INFO - Return code: 0 05:15:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:15:24 ERROR - # TBPL SUCCESS # 05:15:24 INFO - Running post-action listener: _resource_record_post_action 05:15:24 INFO - [mozharness: 2017-02-17 13:15:24.196216Z] Finished run-tests step (success) 05:15:24 INFO - Running post-run listener: _resource_record_post_run 05:15:24 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 358363136; Read time: 8; Write time: 380172 05:15:24 INFO - TinderboxPrint: CPU usage
13.1% 05:15:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:15:24 INFO - TinderboxPrint: I/O write bytes / time
358,363,136 / 380,172 05:15:24 INFO - TinderboxPrint: CPU idle
8,360.6 (86.8%) 05:15:24 INFO - TinderboxPrint: CPU user
1,217.5 (12.6%) 05:15:24 INFO - TinderboxPrint: Swap in / out
0 / 0 05:15:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105422848; Read time: 0; Write time: 145912 05:15:24 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 252940288; Read time: 0; Write time: 234260 05:15:24 INFO - Running post-run listener: _upload_blobber_files 05:15:24 INFO - Blob upload gear active. 05:15:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:15:24 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 05:15:24 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:15:24 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:15:25 INFO - (blobuploader) - INFO - Open directory for files ... 05:15:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:15:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:15:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:26 INFO - (blobuploader) - INFO - Done attempting. 05:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:15:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:29 INFO - (blobuploader) - INFO - Done attempting. 05:15:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:15:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:15:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:29 INFO - (blobuploader) - INFO - Done attempting. 05:15:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:15:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:15:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:30 INFO - (blobuploader) - INFO - Done attempting. 05:15:30 INFO - (blobuploader) - INFO - Iteration through files over. 05:15:30 INFO - Return code: 0 05:15:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:15:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:15:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ab02798312f147a932a830adbda02a31ea7553be396c0f7758e6391eb40d9b93fc70cbf883e6db3b60a7c9c0fe37ec55a24891f682d048ca7eda12644a0677d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9130ed0cf4fda159ad5c7538bdeb1ababde712c79d2fab0ddb476eaeb1234d891e8985a9fb83e76c265f79350d3f43b60a5697c1da82974de096a7ed72822ad0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f3ecf601e23448077df4eba4874f1a93907b03a072602f2cd1719f30401feecfd7837d3d2c040b77bf6e8db29e74fa1ccb67450ae4a69f95cd9ffb48c033816", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e03aa4baaf6261e3e16b8906c9b08661dae08315826116d007ab37c53ead918225c92c628f34235bebda2f514ec67cebaedd2b1bbff35642b2132ac207c412fc"} 05:15:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:15:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:15:30 INFO - Contents: 05:15:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ab02798312f147a932a830adbda02a31ea7553be396c0f7758e6391eb40d9b93fc70cbf883e6db3b60a7c9c0fe37ec55a24891f682d048ca7eda12644a0677d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9130ed0cf4fda159ad5c7538bdeb1ababde712c79d2fab0ddb476eaeb1234d891e8985a9fb83e76c265f79350d3f43b60a5697c1da82974de096a7ed72822ad0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f3ecf601e23448077df4eba4874f1a93907b03a072602f2cd1719f30401feecfd7837d3d2c040b77bf6e8db29e74fa1ccb67450ae4a69f95cd9ffb48c033816", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e03aa4baaf6261e3e16b8906c9b08661dae08315826116d007ab37c53ead918225c92c628f34235bebda2f514ec67cebaedd2b1bbff35642b2132ac207c412fc"} 05:15:30 INFO - Running post-run listener: copy_logs_to_upload_dir 05:15:30 INFO - Copying logs to upload dir... 05:15:30 INFO - mkdir: /builds/slave/test/build/upload/logs 05:15:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.186959 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-17 05:15:29.975340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 05:15:29.978517) ========= 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=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 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-Non-PGO/sha512/7ab02798312f147a932a830adbda02a31ea7553be396c0f7758e6391eb40d9b93fc70cbf883e6db3b60a7c9c0fe37ec55a24891f682d048ca7eda12644a0677d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9130ed0cf4fda159ad5c7538bdeb1ababde712c79d2fab0ddb476eaeb1234d891e8985a9fb83e76c265f79350d3f43b60a5697c1da82974de096a7ed72822ad0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f3ecf601e23448077df4eba4874f1a93907b03a072602f2cd1719f30401feecfd7837d3d2c040b77bf6e8db29e74fa1ccb67450ae4a69f95cd9ffb48c033816", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e03aa4baaf6261e3e16b8906c9b08661dae08315826116d007ab37c53ead918225c92c628f34235bebda2f514ec67cebaedd2b1bbff35642b2132ac207c412fc"} build_url:https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/eKk5bPybTdCK3v1cu3R2Uw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ab02798312f147a932a830adbda02a31ea7553be396c0f7758e6391eb40d9b93fc70cbf883e6db3b60a7c9c0fe37ec55a24891f682d048ca7eda12644a0677d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9130ed0cf4fda159ad5c7538bdeb1ababde712c79d2fab0ddb476eaeb1234d891e8985a9fb83e76c265f79350d3f43b60a5697c1da82974de096a7ed72822ad0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f3ecf601e23448077df4eba4874f1a93907b03a072602f2cd1719f30401feecfd7837d3d2c040b77bf6e8db29e74fa1ccb67450ae4a69f95cd9ffb48c033816", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e03aa4baaf6261e3e16b8906c9b08661dae08315826116d007ab37c53ead918225c92c628f34235bebda2f514ec67cebaedd2b1bbff35642b2132ac207c412fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 05:15:30.006340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:15:30.006654) ========= 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=eca2ab57546de7b41f256b19000001ef-1487336064.630010-1990883087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:15:30.086920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 05:15:30.087247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 05:15:30.087537) ========= ========= Total master_lag: 0.16 =========