builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1487652804.16 results: success (0) revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.156579) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.156991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.157278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.174100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.174624) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 20:53:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-02-20 20:53:24 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166504 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.355426) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.355723) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018267 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.405056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:24.405467) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --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 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 20:53:24,658 truncating revision to first 12 chars 2017-02-20 20:53:24,658 Setting DEBUG logging. 2017-02-20 20:53:24,658 attempt 1/10 2017-02-20 20:53:24,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/276bd9ed3dbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 20:53:25,120 unpacking tar archive at: mozilla-central-276bd9ed3dbf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.757611 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:25.176001) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:25.176328) ========= script_repo_revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:25.176706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:25.176963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 20:53:25.187012) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 20:53:25.187295) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:53:25 INFO - MultiFileLogger online at 20170220 20:53:25 in /builds/slave/test 20:53:25 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 20:53:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:53:25 INFO - {'append_to_log': False, 20:53:25 INFO - 'base_work_dir': '/builds/slave/test', 20:53:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:53:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:53:25 INFO - 'branch': 'Firefox-Non-PGO', 20:53:25 INFO - 'buildbot_json_path': 'buildprops.json', 20:53:25 INFO - 'config_files': ('talos/linux_config.py',), 20:53:25 INFO - 'default_actions': ('clobber', 20:53:25 INFO - 'read-buildbot-config', 20:53:25 INFO - 'download-and-extract', 20:53:25 INFO - 'populate-webroot', 20:53:25 INFO - 'create-virtualenv', 20:53:25 INFO - 'install', 20:53:25 INFO - 'run-tests'), 20:53:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:53:25 INFO - 'download_minidump_stackwalk': True, 20:53:25 INFO - 'download_symbols': 'ondemand', 20:53:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:53:25 INFO - 'tooltool.py': '/tools/tooltool.py', 20:53:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:53:25 INFO - '/tools/misc-python/virtualenv.py')}, 20:53:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:53:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:53:25 INFO - 'gecko_profile': False, 20:53:25 INFO - 'gecko_profile_interval': 0, 20:53:25 INFO - 'installer_path': 'installer.exe', 20:53:25 INFO - 'log_level': 'info', 20:53:25 INFO - 'log_name': 'talos', 20:53:25 INFO - 'log_to_console': True, 20:53:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:53:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:53:25 INFO - 'opt_config_files': (), 20:53:25 INFO - 'pip_index': False, 20:53:25 INFO - 'suite': 'dromaeojs', 20:53:25 INFO - 'system_bits': '32', 20:53:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:53:25 INFO - 'title': 'talos-linux64-ix-037', 20:53:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:53:25 INFO - 'use_talos_json': True, 20:53:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:53:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:53:25 INFO - 'work_dir': 'build'} 20:53:25 INFO - [mozharness: 2017-02-21 04:53:25.538503Z] Running clobber step. 20:53:25 INFO - Running pre-action listener: _resource_record_pre_action 20:53:25 INFO - Running main action method: clobber 20:53:25 INFO - rmtree: /builds/slave/test/build 20:53:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:53:26 INFO - Running post-action listener: _resource_record_post_action 20:53:26 INFO - [mozharness: 2017-02-21 04:53:26.993677Z] Finished clobber step (success) 20:53:26 INFO - [mozharness: 2017-02-21 04:53:26.993788Z] Running read-buildbot-config step. 20:53:26 INFO - Running pre-action listener: _resource_record_pre_action 20:53:26 INFO - Running main action method: read_buildbot_config 20:53:26 INFO - Using buildbot properties: 20:53:26 INFO - { 20:53:26 INFO - "project": "", 20:53:26 INFO - "product": "firefox", 20:53:26 INFO - "who": "philringnalda@gmail.com", 20:53:26 INFO - "installer_path": "public/build/target.tar.bz2", 20:53:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 20:53:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:53:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 20:53:26 INFO - "stage_platform": "linux64", 20:53:26 INFO - "basedir": "/builds/slave/test", 20:53:26 INFO - "buildnumber": 988, 20:53:26 INFO - "platform": "ubuntu64_hw", 20:53:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:53:26 INFO - "slavebuilddir": "test", 20:53:26 INFO - "taskId": "FTQuGxhCQ0mFmJI6GPN79Q", 20:53:26 INFO - "branch": "mozilla-central", 20:53:26 INFO - "script_repo_revision": "production", 20:53:26 INFO - "revision": "276bd9ed3dbf85f4a6dac67df54a5f2631804de5", 20:53:26 INFO - "slavename": "talos-linux64-ix-037", 20:53:26 INFO - "repo_path": "mozilla-central" 20:53:26 INFO - } 20:53:26 INFO - Finding installer, test and symbols from parent task. 20:53:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTQuGxhCQ0mFmJI6GPN79Q'}, attempt #1 20:53:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTQuGxhCQ0mFmJI6GPN79Q'}, attempt #1 20:53:27 INFO - Task dependencies: Bhgef52NTumuZTqjW5EH9g 20:53:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g'}, attempt #1 20:53:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/test_packages.json 20:53:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip 20:53:28 INFO - Running post-action listener: _resource_record_post_action 20:53:28 INFO - [mozharness: 2017-02-21 04:53:28.383777Z] Finished read-buildbot-config step (success) 20:53:28 INFO - [mozharness: 2017-02-21 04:53:28.383994Z] Running download-and-extract step. 20:53:28 INFO - Running pre-action listener: _resource_record_pre_action 20:53:28 INFO - Running main action method: download_and_extract 20:53:28 INFO - mkdir: /builds/slave/test/build/tests 20:53: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')]} 20:53:28 INFO - trying https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json 20:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:53:28 INFO - Downloaded 896 bytes. 20:53:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:53:28 INFO - Using the following test package requirements: 20:53:28 INFO - {u'common': [u'target.common.tests.zip'], 20:53:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:53:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:53:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:53:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:53:28 INFO - u'mozbase': [u'target.common.tests.zip'], 20:53:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:53:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:53:28 INFO - u'web-platform': [u'target.common.tests.zip', 20:53:28 INFO - u'target.web-platform.tests.zip'], 20:53:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:53:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:53:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip 20:53:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:53:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip into memory 20:53:30 INFO - Expected file size: 57165246 20:53:30 INFO - Obtained file size: 57165246 20:53:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:53:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip 20:53:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:53:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.common.tests.zip into memory 20:53:40 INFO - Expected file size: 57165246 20:53:40 INFO - Obtained file size: 57165246 20:53:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip 20:53:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:53:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.talos.tests.zip into memory 20:53:52 INFO - Expected file size: 14404319 20:53:52 INFO - Obtained file size: 14404319 20:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:52 INFO - trying https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:53:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:53:54 INFO - Downloaded 61459462 bytes. 20:53:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:54 INFO - mkdir: /builds/slave/test/properties 20:53:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:53:54 INFO - Writing to file /builds/slave/test/properties/build_url 20:53:54 INFO - Contents: 20:53:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 20:53:54 INFO - Running post-action listener: _resource_record_post_action 20:53:54 INFO - Running post-action listener: set_extra_try_arguments 20:53:54 INFO - [mozharness: 2017-02-21 04:53:54.813216Z] Finished download-and-extract step (success) 20:53:54 INFO - [mozharness: 2017-02-21 04:53:54.813312Z] Running populate-webroot step. 20:53:54 INFO - Running pre-action listener: _resource_record_pre_action 20:53:54 INFO - Running main action method: populate_webroot 20:53:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:53:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:53:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:53:54 INFO - '--tppagecycles', 20:53:54 INFO - '7'], 20:53:54 INFO - 'tests': ['tsvgm']}}, 20:53:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:53:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:53:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:53:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:53:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:53:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:53:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['damp', 'tps']}, 20:53:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'tests': ['damp', 'tps']}, 20:53:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['dromaeo_dom']}, 20:53:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:53:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['basic_compositor_video']}, 20:53:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:53:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'other-e10s': {'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['a11yr', 20:53:54 INFO - 'ts_paint', 20:53:54 INFO - 'tpaint', 20:53:54 INFO - 'sessionrestore', 20:53:54 INFO - 'sessionrestore_no_auto_restore', 20:53:54 INFO - 'tabpaint']}, 20:53:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['tsvgx', 20:53:54 INFO - 'tsvgr_opacity', 20:53:54 INFO - 'tart', 20:53:54 INFO - 'tscrollx', 20:53:54 INFO - 'cart', 20:53:54 INFO - 'tsvg_static']}, 20:53:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:53:54 INFO - 'tsvgr_opacity', 20:53:54 INFO - 'tart', 20:53:54 INFO - 'tscrollx', 20:53:54 INFO - 'cart', 20:53:54 INFO - 'tsvg_static']}, 20:53:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'talos_options': ['--disable-e10s'], 20:53:54 INFO - 'tests': ['tp5o']}, 20:53:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:53:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'talos_options': ['--disable-e10s', 20:53:54 INFO - '--xperf_path', 20:53:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:53:54 INFO - 'tests': ['tp5n']}, 20:53:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:53:54 INFO - 'talos_options': ['--xperf_path', 20:53:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:53:54 INFO - 'tests': ['tp5n']}}, 20:53:54 INFO - 'talos.zip': {'path': '', 20:53:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:53:54 INFO - Running post-action listener: _resource_record_post_action 20:53:54 INFO - [mozharness: 2017-02-21 04:53:54.829791Z] Finished populate-webroot step (success) 20:53:54 INFO - [mozharness: 2017-02-21 04:53:54.829894Z] Running create-virtualenv step. 20:53:54 INFO - Running pre-action listener: _resource_record_pre_action 20:53:54 INFO - Running main action method: create_virtualenv 20:53:54 INFO - Creating virtualenv /builds/slave/test/build/venv 20:53: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 20:53:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:53:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:53:54 INFO - Using real prefix '/usr' 20:53:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:53:55 INFO - Installing distribute.............................................................................................................................................................................................done. 20:53:57 INFO - Installing pip.................done. 20:53:57 INFO - Return code: 0 20:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:53:57 INFO - Reading from file tmpfile_stdout 20:53:57 INFO - Output received: 20:53:57 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) 20:53:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:53: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')]} 20:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53: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')]} 20:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:53: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', 'pip>=1.5'] in /builds/slave/test/build 20:53: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 pip>=1.5 20:53:57 INFO - Using env: {'DISPLAY': ':0', 20:53:57 INFO - 'HOME': '/home/cltbld', 20:53:57 INFO - 'LANG': 'en_US.UTF-8', 20:53:57 INFO - 'LANGUAGE': 'en_US:en', 20:53:57 INFO - 'LOGNAME': 'cltbld', 20:53:57 INFO - 'MAIL': '/var/mail/cltbld', 20:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:53:57 INFO - 'MOZ_NO_REMOTE': '1', 20:53:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:53:57 INFO - 'NO_EM_RESTART': '1', 20:53:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:53:57 INFO - 'PWD': '/builds/slave/test', 20:53:57 INFO - 'SHELL': '/bin/bash', 20:53:57 INFO - 'SHLVL': '1', 20:53:57 INFO - 'TERM': 'linux', 20:53:57 INFO - 'TMOUT': '86400', 20:53:57 INFO - 'USER': 'cltbld', 20:53:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:53:57 INFO - '_': '/tools/buildbot/bin/python'} 20:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:53: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 20:53:57 INFO - Cleaning up... 20:53:57 INFO - Return code: 0 20:53:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:53: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')]} 20:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53: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')]} 20:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:53: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:53: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 20:53: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 20:53:57 INFO - Using env: {'DISPLAY': ':0', 20:53:57 INFO - 'HOME': '/home/cltbld', 20:53:57 INFO - 'LANG': 'en_US.UTF-8', 20:53:57 INFO - 'LANGUAGE': 'en_US:en', 20:53:57 INFO - 'LOGNAME': 'cltbld', 20:53:57 INFO - 'MAIL': '/var/mail/cltbld', 20:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:53:57 INFO - 'MOZ_NO_REMOTE': '1', 20:53:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:53:57 INFO - 'NO_EM_RESTART': '1', 20:53:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:53:57 INFO - 'PWD': '/builds/slave/test', 20:53:57 INFO - 'SHELL': '/bin/bash', 20:53:57 INFO - 'SHLVL': '1', 20:53:57 INFO - 'TERM': 'linux', 20:53:57 INFO - 'TMOUT': '86400', 20:53:57 INFO - 'USER': 'cltbld', 20:53:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:53:57 INFO - '_': '/tools/buildbot/bin/python'} 20:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:53:58 INFO - Downloading/unpacking psutil>=3.1.1 20:53:58 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 20:53:58 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 20:53:58 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 20:53:58 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 20:53:58 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 20:53:58 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 20:54:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:54:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:00 INFO - Installing collected packages: psutil 20:54:00 INFO - Running setup.py install for psutil 20:54:00 INFO - building 'psutil._psutil_linux' extension 20:54:00 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 20:54:00 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 20:54:00 INFO - building 'psutil._psutil_posix' extension 20:54:00 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 20:54:00 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 20:54:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:00 INFO - Successfully installed psutil 20:54:00 INFO - Cleaning up... 20:54:00 INFO - Return code: 0 20:54:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54: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 20:54: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 20:54:00 INFO - Using env: {'DISPLAY': ':0', 20:54:00 INFO - 'HOME': '/home/cltbld', 20:54:00 INFO - 'LANG': 'en_US.UTF-8', 20:54:00 INFO - 'LANGUAGE': 'en_US:en', 20:54:00 INFO - 'LOGNAME': 'cltbld', 20:54:00 INFO - 'MAIL': '/var/mail/cltbld', 20:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:00 INFO - 'MOZ_NO_REMOTE': '1', 20:54:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:00 INFO - 'NO_EM_RESTART': '1', 20:54:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:00 INFO - 'PWD': '/builds/slave/test', 20:54:00 INFO - 'SHELL': '/bin/bash', 20:54:00 INFO - 'SHLVL': '1', 20:54:00 INFO - 'TERM': 'linux', 20:54:00 INFO - 'TMOUT': '86400', 20:54:00 INFO - 'USER': 'cltbld', 20:54:00 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:00 INFO - '_': '/tools/buildbot/bin/python'} 20:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:54:02 INFO - Installing collected packages: mozsystemmonitor 20:54:02 INFO - Running setup.py install for mozsystemmonitor 20:54:03 INFO - Successfully installed mozsystemmonitor 20:54:03 INFO - Cleaning up... 20:54:03 INFO - Return code: 0 20:54:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:54:03 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')]} 20:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:03 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')]} 20:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:03 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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54:03 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 20:54:03 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 20:54:03 INFO - Using env: {'DISPLAY': ':0', 20:54:03 INFO - 'HOME': '/home/cltbld', 20:54:03 INFO - 'LANG': 'en_US.UTF-8', 20:54:03 INFO - 'LANGUAGE': 'en_US:en', 20:54:03 INFO - 'LOGNAME': 'cltbld', 20:54:03 INFO - 'MAIL': '/var/mail/cltbld', 20:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:03 INFO - 'MOZ_NO_REMOTE': '1', 20:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:03 INFO - 'NO_EM_RESTART': '1', 20:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:03 INFO - 'PWD': '/builds/slave/test', 20:54:03 INFO - 'SHELL': '/bin/bash', 20:54:03 INFO - 'SHLVL': '1', 20:54:03 INFO - 'TERM': 'linux', 20:54:03 INFO - 'TMOUT': '86400', 20:54:03 INFO - 'USER': 'cltbld', 20:54:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:03 INFO - '_': '/tools/buildbot/bin/python'} 20:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:03 INFO - Downloading/unpacking jsonschema==2.5.1 20:54:03 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 20:54:03 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 20:54:03 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 20:54:03 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 20:54:03 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 20:54:03 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 20:54:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:54:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:05 INFO - Downloading functools32-3.2.3-2.tar.gz 20:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:54:05 INFO - warning: no files found matching '*.txt' 20:54:05 INFO - no previously-included directories found matching 'build' 20:54:05 INFO - no previously-included directories found matching 'dist' 20:54:05 INFO - no previously-included directories found matching '.git*' 20:54:05 INFO - Installing collected packages: jsonschema, functools32 20:54:05 INFO - Running setup.py install for functools32 20:54:05 INFO - warning: no files found matching '*.txt' 20:54:05 INFO - no previously-included directories found matching 'build' 20:54:05 INFO - no previously-included directories found matching 'dist' 20:54:05 INFO - no previously-included directories found matching '.git*' 20:54:05 INFO - Successfully installed jsonschema functools32 20:54:05 INFO - Cleaning up... 20:54:05 INFO - Return code: 0 20:54:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54: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 20:54: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 20:54:05 INFO - Using env: {'DISPLAY': ':0', 20:54:05 INFO - 'HOME': '/home/cltbld', 20:54:05 INFO - 'LANG': 'en_US.UTF-8', 20:54:05 INFO - 'LANGUAGE': 'en_US:en', 20:54:05 INFO - 'LOGNAME': 'cltbld', 20:54:05 INFO - 'MAIL': '/var/mail/cltbld', 20:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:05 INFO - 'MOZ_NO_REMOTE': '1', 20:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:05 INFO - 'NO_EM_RESTART': '1', 20:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:05 INFO - 'PWD': '/builds/slave/test', 20:54:05 INFO - 'SHELL': '/bin/bash', 20:54:05 INFO - 'SHLVL': '1', 20:54:05 INFO - 'TERM': 'linux', 20:54:05 INFO - 'TMOUT': '86400', 20:54:05 INFO - 'USER': 'cltbld', 20:54:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:05 INFO - '_': '/tools/buildbot/bin/python'} 20:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:54:06 INFO - Cleaning up... 20:54:06 INFO - Return code: 0 20:54:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:54:06 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')]} 20:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:06 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')]} 20:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:06 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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54:06 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 20:54:06 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 20:54:06 INFO - Using env: {'DISPLAY': ':0', 20:54:06 INFO - 'HOME': '/home/cltbld', 20:54:06 INFO - 'LANG': 'en_US.UTF-8', 20:54:06 INFO - 'LANGUAGE': 'en_US:en', 20:54:06 INFO - 'LOGNAME': 'cltbld', 20:54:06 INFO - 'MAIL': '/var/mail/cltbld', 20:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:06 INFO - 'MOZ_NO_REMOTE': '1', 20:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:06 INFO - 'NO_EM_RESTART': '1', 20:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:06 INFO - 'PWD': '/builds/slave/test', 20:54:06 INFO - 'SHELL': '/bin/bash', 20:54:06 INFO - 'SHLVL': '1', 20:54:06 INFO - 'TERM': 'linux', 20:54:06 INFO - 'TMOUT': '86400', 20:54:06 INFO - 'USER': 'cltbld', 20:54:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:06 INFO - '_': '/tools/buildbot/bin/python'} 20:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:06 INFO - Downloading/unpacking blobuploader==1.2.4 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:08 INFO - Downloading blobuploader-1.2.4.tar.gz 20:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:54:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:54:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:08 INFO - Downloading docopt-0.6.1.tar.gz 20:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:54:09 INFO - Installing collected packages: blobuploader, requests, docopt 20:54:09 INFO - Running setup.py install for blobuploader 20:54:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:54:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:54:09 INFO - Running setup.py install for requests 20:54:09 INFO - Running setup.py install for docopt 20:54:09 INFO - Successfully installed blobuploader requests docopt 20:54:09 INFO - Cleaning up... 20:54:09 INFO - Return code: 0 20:54:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54: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 20:54: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 20:54:09 INFO - Using env: {'DISPLAY': ':0', 20:54:09 INFO - 'HOME': '/home/cltbld', 20:54:09 INFO - 'LANG': 'en_US.UTF-8', 20:54:09 INFO - 'LANGUAGE': 'en_US:en', 20:54:09 INFO - 'LOGNAME': 'cltbld', 20:54:09 INFO - 'MAIL': '/var/mail/cltbld', 20:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:09 INFO - 'MOZ_NO_REMOTE': '1', 20:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:09 INFO - 'NO_EM_RESTART': '1', 20:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:09 INFO - 'PWD': '/builds/slave/test', 20:54:09 INFO - 'SHELL': '/bin/bash', 20:54:09 INFO - 'SHLVL': '1', 20:54:09 INFO - 'TERM': 'linux', 20:54:09 INFO - 'TMOUT': '86400', 20:54:09 INFO - 'USER': 'cltbld', 20:54:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:09 INFO - '_': '/tools/buildbot/bin/python'} 20:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:09 INFO - Running setup.py (path:/tmp/pip-g5IN01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:09 INFO - Running setup.py (path:/tmp/pip-hp_8qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:10 INFO - Running setup.py (path:/tmp/pip-rmAr9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:10 INFO - Running setup.py (path:/tmp/pip-uTMHwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:10 INFO - Running setup.py (path:/tmp/pip-moicQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:10 INFO - Running setup.py (path:/tmp/pip-PyTmwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:10 INFO - Running setup.py (path:/tmp/pip-4BwRRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:10 INFO - Running setup.py (path:/tmp/pip-RjcT9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:10 INFO - Running setup.py (path:/tmp/pip-s4sNRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:10 INFO - Running setup.py (path:/tmp/pip-s1Zs_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:10 INFO - Running setup.py (path:/tmp/pip-gS9r12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:10 INFO - Running setup.py (path:/tmp/pip-pKbzkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:10 INFO - Running setup.py (path:/tmp/pip-EQOzwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:10 INFO - Running setup.py (path:/tmp/pip-ElUxLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:10 INFO - Running setup.py (path:/tmp/pip-7eTb2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:11 INFO - Running setup.py (path:/tmp/pip-BM2amL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:11 INFO - Running setup.py (path:/tmp/pip-eesF5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:54:11 INFO - Running setup.py install for manifestparser 20:54:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Running setup.py install for mozcrash 20:54:11 INFO - Running setup.py install for mozdebug 20:54:11 INFO - Running setup.py install for mozdevice 20:54:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Running setup.py install for mozfile 20:54:11 INFO - Running setup.py install for mozhttpd 20:54:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Running setup.py install for mozinfo 20:54:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Running setup.py install for mozInstall 20:54:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:54:11 INFO - Running setup.py install for mozleak 20:54:12 INFO - Running setup.py install for mozlog 20:54:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for moznetwork 20:54:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozprocess 20:54:12 INFO - Running setup.py install for mozprofile 20:54:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozrunner 20:54:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Running setup.py install for mozscreenshot 20:54:12 INFO - Running setup.py install for moztest 20:54:12 INFO - Running setup.py install for mozversion 20:54:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:54:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:54:12 INFO - Cleaning up... 20:54:12 INFO - Return code: 0 20:54:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54: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 20:54: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 20:54:12 INFO - Using env: {'DISPLAY': ':0', 20:54:12 INFO - 'HOME': '/home/cltbld', 20:54:12 INFO - 'LANG': 'en_US.UTF-8', 20:54:12 INFO - 'LANGUAGE': 'en_US:en', 20:54:12 INFO - 'LOGNAME': 'cltbld', 20:54:12 INFO - 'MAIL': '/var/mail/cltbld', 20:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:12 INFO - 'MOZ_NO_REMOTE': '1', 20:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:12 INFO - 'NO_EM_RESTART': '1', 20:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:12 INFO - 'PWD': '/builds/slave/test', 20:54:12 INFO - 'SHELL': '/bin/bash', 20:54:12 INFO - 'SHLVL': '1', 20:54:12 INFO - 'TERM': 'linux', 20:54:12 INFO - 'TMOUT': '86400', 20:54:12 INFO - 'USER': 'cltbld', 20:54:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:12 INFO - '_': '/tools/buildbot/bin/python'} 20:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:13 INFO - Running setup.py (path:/tmp/pip-iwR83J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:13 INFO - Running setup.py (path:/tmp/pip-zREpR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:13 INFO - Running setup.py (path:/tmp/pip-yCQwsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:13 INFO - Running setup.py (path:/tmp/pip-X5gwEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:13 INFO - Running setup.py (path:/tmp/pip-9qqzIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:13 INFO - Running setup.py (path:/tmp/pip-iGTQUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:13 INFO - Running setup.py (path:/tmp/pip-jv7F_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:13 INFO - Running setup.py (path:/tmp/pip-wzkopW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:13 INFO - Running setup.py (path:/tmp/pip-P6dGs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:13 INFO - Running setup.py (path:/tmp/pip-d2N9p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:13 INFO - Running setup.py (path:/tmp/pip-8Qt4D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54: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)) 20:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:13 INFO - Running setup.py (path:/tmp/pip-rpRHoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:14 INFO - Running setup.py (path:/tmp/pip-C4rw0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:14 INFO - Running setup.py (path:/tmp/pip-aDDY9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:14 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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:14 INFO - Running setup.py (path:/tmp/pip-UzhWnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54: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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:14 INFO - Running setup.py (path:/tmp/pip-Qxvozo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54: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)) 20:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:14 INFO - Running setup.py (path:/tmp/pip-sOQqI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54: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)) 20:54: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)) 20:54: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)) 20:54: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)) 20:54: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)) 20:54: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)) 20:54:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:16 INFO - Downloading blessings-1.6.tar.gz 20:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:54:16 INFO - Installing collected packages: blessings 20:54:16 INFO - Running setup.py install for blessings 20:54:16 INFO - Successfully installed blessings 20:54:16 INFO - Cleaning up... 20:54:16 INFO - Return code: 0 20:54:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:54:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:16 INFO - Reading from file tmpfile_stdout 20:54:16 INFO - Current package versions: 20:54:16 INFO - argparse == 1.2.1 20:54:16 INFO - blessings == 1.6 20:54:16 INFO - blobuploader == 1.2.4 20:54:16 INFO - docopt == 0.6.1 20:54:16 INFO - functools32 == 3.2.3-2 20:54:16 INFO - jsonschema == 2.5.1 20:54:16 INFO - manifestparser == 1.1 20:54:16 INFO - mozInstall == 1.12 20:54:16 INFO - mozcrash == 1.0 20:54:16 INFO - mozdebug == 0.1 20:54:16 INFO - mozdevice == 0.49 20:54:16 INFO - mozfile == 1.2 20:54:16 INFO - mozhttpd == 0.7 20:54:16 INFO - mozinfo == 0.9 20:54:16 INFO - mozleak == 0.1 20:54:16 INFO - mozlog == 3.4 20:54:16 INFO - moznetwork == 0.27 20:54:16 INFO - mozprocess == 0.25 20:54:16 INFO - mozprofile == 0.28 20:54:16 INFO - mozrunner == 6.13 20:54:16 INFO - mozscreenshot == 0.1 20:54:16 INFO - mozsystemmonitor == 0.3 20:54:16 INFO - moztest == 0.8 20:54:16 INFO - mozversion == 1.4 20:54:16 INFO - psutil == 3.1.1 20:54:16 INFO - requests == 1.2.3 20:54:16 INFO - wsgiref == 0.1.2 20:54:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54: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 20:54: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 20:54:16 INFO - Using env: {'DISPLAY': ':0', 20:54:16 INFO - 'HOME': '/home/cltbld', 20:54:16 INFO - 'LANG': 'en_US.UTF-8', 20:54:16 INFO - 'LANGUAGE': 'en_US:en', 20:54:16 INFO - 'LOGNAME': 'cltbld', 20:54:16 INFO - 'MAIL': '/var/mail/cltbld', 20:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:16 INFO - 'MOZ_NO_REMOTE': '1', 20:54:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:16 INFO - 'NO_EM_RESTART': '1', 20:54:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:16 INFO - 'PWD': '/builds/slave/test', 20:54:16 INFO - 'SHELL': '/bin/bash', 20:54:16 INFO - 'SHLVL': '1', 20:54:16 INFO - 'TERM': 'linux', 20:54:16 INFO - 'TMOUT': '86400', 20:54:16 INFO - 'USER': 'cltbld', 20:54:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:16 INFO - '_': '/tools/buildbot/bin/python'} 20:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 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)) 20:54:17 INFO - Cleaning up... 20:54:17 INFO - Return code: 0 20:54:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', '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 20:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:54:17 INFO - Using env: {'DISPLAY': ':0', 20:54:17 INFO - 'HOME': '/home/cltbld', 20:54:17 INFO - 'LANG': 'en_US.UTF-8', 20:54:17 INFO - 'LANGUAGE': 'en_US:en', 20:54:17 INFO - 'LOGNAME': 'cltbld', 20:54:17 INFO - 'MAIL': '/var/mail/cltbld', 20:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:17 INFO - 'MOZ_NO_REMOTE': '1', 20:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:17 INFO - 'NO_EM_RESTART': '1', 20:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:17 INFO - 'PWD': '/builds/slave/test', 20:54:17 INFO - 'SHELL': '/bin/bash', 20:54:17 INFO - 'SHLVL': '1', 20:54:17 INFO - 'TERM': 'linux', 20:54:17 INFO - 'TMOUT': '86400', 20:54:17 INFO - 'USER': 'cltbld', 20:54:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:17 INFO - '_': '/tools/buildbot/bin/python'} 20:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:54:17 INFO - Cleaning up... 20:54:17 INFO - Return code: 0 20:54:17 INFO - Running post-action listener: _resource_record_post_action 20:54:17 INFO - Running post-action listener: _start_resource_monitoring 20:54:17 INFO - Starting resource monitoring. 20:54:17 INFO - [mozharness: 2017-02-21 04:54:17.390896Z] Finished create-virtualenv step (success) 20:54:17 INFO - [mozharness: 2017-02-21 04:54:17.391366Z] Running install step. 20:54:17 INFO - Running pre-action listener: _resource_record_pre_action 20:54:17 INFO - Running main action method: install 20:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:17 INFO - Reading from file tmpfile_stdout 20:54:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:54:17 INFO - Reading from file tmpfile_stdout 20:54:17 INFO - Output received: 20:54:17 INFO - Usage: mozinstall [options] installer 20:54:17 INFO - Options: 20:54:17 INFO - -h, --help show this help message and exit 20:54:17 INFO - -d DEST, --destination=DEST 20:54:17 INFO - Directory to install application into. [default: 20:54:17 INFO - "/builds/slave/test"] 20:54:17 INFO - --app=APP Application being installed. [default: firefox] 20:54:17 INFO - mkdir: /builds/slave/test/build/application 20:54: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'] 20:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:54:33 INFO - Reading from file tmpfile_stdout 20:54:33 INFO - Output received: 20:54:33 INFO - /builds/slave/test/build/application/firefox/firefox 20:54:33 INFO - Running post-action listener: _resource_record_post_action 20:54:33 INFO - [mozharness: 2017-02-21 04:54:33.096875Z] Finished install step (success) 20:54:33 INFO - [mozharness: 2017-02-21 04:54:33.097149Z] Running run-tests step. 20:54:33 INFO - Running pre-action listener: _resource_record_pre_action 20:54:33 INFO - Running main action method: run_tests 20:54:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:54:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:54:33 INFO - Python 2.7.3 20:54:33 INFO - Return code: 0 20:54:33 INFO - grabbing minidump binary from tooltool 20:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:33 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 0x254a290>, '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 0x254ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254b040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:54:33 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 20:54:33 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 20:54:33 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 20:54:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:54:33 INFO - Return code: 0 20:54:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:54:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:54:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:54:33 INFO - ENV: RUST_BACKTRACE is now 1 20:54:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:54:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:54:33 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/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 20:54:33 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/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 20:54:33 INFO - Using env: {'DISPLAY': ':0', 20:54:33 INFO - 'HOME': '/home/cltbld', 20:54:33 INFO - 'LANG': 'en_US.UTF-8', 20:54:33 INFO - 'LANGUAGE': 'en_US:en', 20:54:33 INFO - 'LOGNAME': 'cltbld', 20:54:33 INFO - 'MAIL': '/var/mail/cltbld', 20:54:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:54:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:33 INFO - 'MOZ_NO_REMOTE': '1', 20:54:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:54:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:54:33 INFO - 'NO_EM_RESTART': '1', 20:54:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:33 INFO - 'PWD': '/builds/slave/test', 20:54:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:54:33 INFO - 'RUST_BACKTRACE': '1', 20:54:33 INFO - 'SHELL': '/bin/bash', 20:54:33 INFO - 'SHLVL': '1', 20:54:33 INFO - 'TERM': 'linux', 20:54:33 INFO - 'TMOUT': '86400', 20:54:33 INFO - 'USER': 'cltbld', 20:54:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826', 20:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:33 INFO - '_': '/tools/buildbot/bin/python'} 20:54:33 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/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 20:54:33 INFO - mozversion application_buildid: 20170221042705 20:54:33 INFO - mozversion application_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 20:54:33 INFO - mozversion application_display_name: Nightly 20:54:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:54:33 INFO - mozversion application_name: Firefox 20:54:33 INFO - mozversion application_remotingname: firefox 20:54:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:54:33 INFO - mozversion application_vendor: Mozilla 20:54:33 INFO - mozversion application_version: 54.0a1 20:54:33 INFO - mozversion platform_buildid: 20170221042705 20:54:33 INFO - mozversion platform_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 20:54:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:54:33 INFO - mozversion platform_version: 54.0a1 20:54:33 INFO - using testdate: 1487652873 20:54:33 INFO - actual date: 1487652873 20:54:33 INFO - starting webserver on 'localhost:39135' 20:54:33 INFO - SUITE-START | Running 2 tests 20:54:33 INFO - TEST-START | dromaeo_css 20:54:33 INFO - operating with platform_type : linux_ 20:54:33 INFO - Initialising browser for dromaeo_css test... 20:54:33 INFO - TEST-INFO | started process 5531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc2Oimz/profile http://localhost:39135/getInfo.html) 20:54:36 INFO - PROCESS | 5531 | Iterating over profile /tmp/tmpc2Oimz/profile 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/key3.db = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | __metrics Screen width/height:1600/1200 20:54:37 INFO - PROCESS | 5531 | colorDepth:24 20:54:37 INFO - PROCESS | 5531 | Browser inner width/height: 1024/697 20:54:37 INFO - PROCESS | 5531 | __metrics 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/bookmarkbackups = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/prefs.js = Mon Feb 20 2017 20:53:52 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/extensions.ini = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | Stat failure 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/places.sqlite-shm = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/extensions = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/localstore.rdf = Mon Feb 20 2017 20:53:52 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/addons.json = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/startupCache = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/safebrowsing = Mon Feb 20 2017 20:54:37 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/extensions.json = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/compatibility.ini = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/crashes = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/user.js = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/places.sqlite-wal = Mon Feb 20 2017 20:54:37 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/places.sqlite = Mon Feb 20 2017 20:54:37 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/.parentlock = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/frequencyCap.json = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/cert8.db = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/gmp = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/pluginreg.dat = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/thumbnails = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/blocklist.xml = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/content-prefs.sqlite = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/permissions.sqlite = Mon Feb 20 2017 20:54:34 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/directoryLinks.json = Mon Feb 20 2017 20:54:35 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/secmod.db = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/cache2 = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/minidumps = Mon Feb 20 2017 20:54:33 GMT-0800 (PST) 20:54:37 INFO - PROCESS | 5531 | No birth date. Using mtime. 20:54:37 INFO - PROCESS | 5531 | Using date: /tmp/tmpc2Oimz/profile/sessionCheckpoints.json = Mon Feb 20 2017 20:54:37 GMT-0800 (PST) 20:54:38 INFO - PROCESS | 5531 | 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" 20:54:38 INFO - PROCESS | 5531 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:54:39 INFO - TEST-INFO | 5531: exit 0 20:54:39 INFO - Browser initialized. 20:54:39 INFO - Running cycle 1/1 for dromaeo_css test... 20:54:39 INFO - TEST-INFO | started process 5620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc2Oimz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:54:51 INFO - PROCESS | 5620 | RSS: Main: 147582976 20:54:51 INFO - PROCESS | 5620 | 20:57:57 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39135/tests/dromaeo/cssquery-ext.html) 20:57:58 INFO - PROCESS | 5620 | RSS: Main: 277336064 20:57:58 INFO - PROCESS | 5620 | 20:59:40 INFO - PROCESS | 5620 | ************************* 20:59:40 INFO - PROCESS | 5620 | A coding exception was thrown and uncaught in a Task. 20:59:40 INFO - PROCESS | 5620 | 20:59:40 ERROR - PROCESS | 5620 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:59:40 INFO - PROCESS | 5620 | Full stack: 20:59:40 INFO - PROCESS | 5620 | ************************* 21:01:01 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-ext.html (next: http://localhost:39135/tests/dromaeo/cssquery-jquery.html) 21:01:02 INFO - PROCESS | 5620 | RSS: Main: 250105856 21:01:02 INFO - PROCESS | 5620 | 21:04:04 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39135/tests/dromaeo/cssquery-mootools.html) 21:04:05 INFO - PROCESS | 5620 | RSS: Main: 226275328 21:04:05 INFO - PROCESS | 5620 | 21:07:07 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39135/tests/dromaeo/cssquery-prototype.html) 21:07:08 INFO - PROCESS | 5620 | RSS: Main: 183033856 21:07:08 INFO - PROCESS | 5620 | 21:10:11 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39135/tests/dromaeo/cssquery-yui.html) 21:10:11 INFO - PROCESS | 5620 | RSS: Main: 190029824 21:10:11 INFO - PROCESS | 5620 | 21:13:14 INFO - PROCESS | 5620 | Cycle 1(1): loaded http://localhost:39135/tests/dromaeo/cssquery-yui.html (next: http://localhost:39135/tests/dromaeo/cssquery-dojo.html) 21:13:15 INFO - PROCESS | 5620 | RSS: Main: 266625024 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | __start_tp_report 21:13:15 INFO - PROCESS | 5620 | _x_x_mozilla_page_load 21:13:15 INFO - PROCESS | 5620 | _x_x_mozilla_page_load_details 21:13:15 INFO - PROCESS | 5620 | |i|pagename|runs| 21:13:15 INFO - PROCESS | 5620 | |0;dojo.html;2497.812664050196;2574.562324404851;2583.6899572051357;2620.004398328636;2625.8293210941274;4612.354270402138;4644.210484217682;4781.856544303672;4816.975915120423;4850.708957462551;4692.929606055912;4740.928886066703;4742.549457801512;4763.190257656198;4802.687825291354;4324.870253892388;4340.262155433568;4379.934300985487;4421.270490369086;4438.556144385568;4377.387165796807;4444.844430444935;4457.59881610655;4470.307102399133;4505.932411013838;4189.245935731567;4221.852235171754;4224.957750422487;4265.360195970598;4286.442762440894;4256.808443620044;4271.9359209611885;4290.914181716348;4299.613034826849;4304.139172165579;4278.935815962763;4295.763732994684;4337.783110844477;4354.412154359153;4364.148990946766;4681.274402467623;4703.059388122355;4737.810487580493;4744.051189762062;4795.928061079087;4909.828156014558;4936.308916751657;4936.555709986083;5070.898582028376;5084.51697088776;5028.472010438924;5037.0681423936685;5040.16837221858;5043.5460808527405;5044.268581055726;4711.858644240679;4715.717056976592;4722.716637001791;4784.043191361672;4807.543283388072;5312.787488500494;5347.705876176848;5350.197470379474;5350.91973620396;5357.785688572413;4821.54195351441;4897.094037603055;4907.043126590281;4977.925331120036;4986.077575648516;5043.3191519145175;5048.1418158913075;5049.293098966147;5051.4695956924725;5051.570616497565;5020.698758074527;5031.270465782441;5034.647574669812;5086;5104.744762761847;5160.071187186342;5162.586993040548;5166.4058633257655;5170.870728231825;5219.634625576249;4145.3781932710335;4163.937540936889;4327.545607711145;4388.780560971939;4390.978045109754;3706.4069748840056;3789.1663833956486;3809.961900381016;3827.5215598050245;3832.329342365075;4396.3625274335045;4398.956010439854;4403.647708183337;4405.3612226227015;4444.266695995125;4912.238603016538;4941.728204948762;5206.401263854629;5215.921761173586;5216.439446711071;4218.78906054696;4260.637845783143;4269.103488267437;4290.528041915387;4291.592298731678;4593.63250939924;4593.793279302439;4628.467726211461;4630.259158534618;4639.095376401637;4809.038192361471;4816.109019831342;4817.349657796293;4821.083994041121;4865.172920603435;4894.755262236945;4920.45874953762;5130.230465430066;5134.10153223177;5183.352080989876;4687.835925742583;4702.177119004092;4712.316714076508;4812.927806082982;4813.663043586916;5104.668196567211;5104.846854594368;5147.227915812508;5154.9690061987;5155.407128180307;5137.075326441276;5174.275601415729;5180.818671346485;5181.611379146504;5190.974045129901;4806.543378379189;4835.2505361669;4959.553640172402;5029.421616514148;5048.621353398583;4969.503049695001;5081.33942587476;5094.643374963865;5097.388313402415;5100.719460429712;4954.702717836942;4970.850874473772;4971.080350135237;4971.080350135237;5023.824166154167;5042.974785126197;5046.21783623539;5053.8736531587;5057.721825299496;5102.642815003062;4663.300504924288;4690.648201384978;4716.339712440193;4789.544993225638;4797.18447863857 21:13:15 INFO - PROCESS | 5620 | |1;ext.html;558.6033915919696;560.3332034878495;568.0768750779982;572.9799457019005;631.8546734251122;27003.189904302853;27583.862080689592;38000.61999380009;39098;39487.80256098723;39288.41067383984;39776.40335394971;39782.80108599453;39795.20409591807;39809.800950995275;7380.483366164371;7534.472586918918;7549.509281896687;7555.093388793325;7558.959081048114;6995.685194166249;7203.747868824592;9628.759281017961;9852.605895764162;9871.901280987206;9920.900790992106;9975;9985.101340879319;9991.90008099917;10008.399496030224;9458.763530911714;9508.47703376314;9511.143997040299;9514.333996620204;9515.476648784314;9550.61797528098;9624.855627165598;9686.806263874754;9693.369930954465;9695.757606059906;37135.44296835522;37279.62720372788;37521.43717844234;37595.81202093972;37629.43555846637;3451.7238620910266;3477.7565570410084;3478.113081164287;3497.5802903651725;3539.681428671406;26973.595396069075;27477.038303659276;27484.862575687195;27607.171784846487;27614.723852761417;3124.8906288280027;3270.9018729438153;3365.125067482448;3519.8768043118366;3524.18943642961;213966;214415;215008;215085.9245703777;215874;51910.480895190565;52187;52517.47482525202;52572.474275256754;52601.7369913148;56059.15911261334;56869.43130568724;56898.7155064222;56900.43099569034;57041.169295982516;13088.08383413171;13360;13368.064235503616;13398.464061437433;13413.0610857241;9937.751556211153;10124.291299608956;10146.594136234615;10199.0820826126;10202.531139412635;16700.164991750364;16825.747613785803;16916.32334706598;16929.42905415531;16984.660306793794;5696.3449203341315;5993.580449368503;6001.2498437695285;6016.157737916695;6073.878522429527;742.4468770765814;766.0960067120851;772.320358084893;779.1429427629562;790.9644066017044;25530.361740956254;26058.869705651352;26274.6058809118;26476.735232647814;26481.07316243922;876.3471213945651;898.8606765951288;904.3895370624801;915.5193523400135;924.4684306523776;13393.263370514715;13532.86467135316;13806.378712957941;14115.435382584581;14150.36323365451;293060;295294;295801;295898;296134;48137.75931120322;48296.75851620719;48323.75838120857;48449;48563.51436485661;1142.3488611491537;1175.788358095551;1183.9348835813942;1186.0748616079472;1197.7963746163173;24218.20830153288;24895.253142405756;25831.225063248134;26023.869880650476;26080.8695956519;40301.79849100853;40848.79575602103;40858;40879;40899.59100409077;258563;260341;260371;260495;261101;20177.596448070664;20816.479588009814;20904.16383344649;34037.65962340444;34665.82667086648;33876.66123338834;34381.31237375289;34423.13942151366;34645.826770865984;34968.65031349654;28630.284242894093;28823.423531529672;28970.7102928968;29324.853375732986;29999.55000674948;28738.568921466613;28989.85505072461;29043.709562904947;29087.7091229085;29407.823687053133;26762.929482821248;26991;26994.86502567553;27020.32449188786;27100.457990839677;24703.38241543976;25179.49641007133;26441.867790660923;26588.884556551715;27000;20572.588548228654;20595.279165229746;20773.376798696063;20847.583048339253;20882.68675969892 21:13:15 INFO - PROCESS | 5620 | |2;jquery.html;341.12161184948746;362.3351150638578;399.71819866993764;399.94800675912126;401.97295908952634;12376.876231237686;12963.027772917034;13408.195508269511;18412.987285699313;18452.538686532844;17810.821891781077;17899.28402863884;18252.543686407847;18416.53958651031;18439.354622588213;5238.973805130979;5392.110301800199;5428.538574221196;5429.837104886859;5432.320959880015;5184.11869982104;5284;5312.76092575835;5330.387005494379;5412.5940554458575;5277.70972596507;5283.049051170788;5283.682979021251;5285.339332583427;5319.228711836781;5071.949280507185;5240.842774716764;5247.553957913582;5279.577633789288;5327.733613319358;4811.639127065484;4811.8075276988875;4817.349657796188;4819.5180481951875;4822.059698358821;21000.789992100188;21030.369088927357;21565.892170539046;21681.891590542105;21912.780872191233;2053.917843286267;2229.6989906362596;2244.6408574628144;2487.2786891801356;2629.6318515407856;8550.70072547464;8673.913260867374;8813.691520796805;8936.151065648754;11652.533898644044;1562.7187106320857;1582.8733701303984;1602.7195240832807;1615.5961009747564;1637.5333030086367;73735.63132184304;73823.26176738218;74609.25390746078;74770;75088.62455687742;10132.49337533135;10231.846522302172;10392.22058345612;10694.786104277871;10891.400972946563;15250.23748812555;15301;15321.6169595761;15345.693086138215;15362.155081470626;7158.749443769547;7279.344858962613;7391.556506609621;7490.737824176128;7505.924940750632;6532.640704761188;6550.541462097703;6760.492963027793;6790.694418751168;6799.388055075067;4316.395704601358;4341.240282950471;4533.52397998212;4603.58567728906;4768.284757286366;1750.6061136244498;1907.990460047691;1945.7956914524054;1953.4042117154404;2010.7486564179478;778.8403377307638;800.3117319105564;802.4802121339001;835.2691395029349;839.1021606880702;16585.087820169767;16765.10609028517;17318.134093295284;17411.56471088233;17588.208530616153;780.0872978615034;787.4881327137407;788.6805843633338;792.5442870349572;805.4442434720141;9012;9416.858747118798;9815.754606134904;10274.589016439259;10280.897191028143;427144;427265;427611;437198;443686;89984.5500772492;92458;92958.53520732353;93231.53384233035;93325;937.6483818568037;957.262907561174;980.2207245240052;980.5538479991586;982.8181786369402;15576.299066541806;15773.369065237262;16370.67258654844;16386.426475073793;16688.415905443253;39372;40338.596614033486;40849;40937;41044.179116416904;54886.72556637191;55490.445095550145;55739.44260557342;55949.72025139848;56583;6814.114165158498;7004.474664400089;7025.718971241093;7179.784606461814;7479.102507699111;11212.27120237182;11860;12216.205946613441;12453.315067671365;12565.623031309075;9846.753831154278;9957.93125448605;9960;10019.845733167404;10262.640807571997;9700.00398930844;10180;10294.176465882561;10597.4701264938;10696.572137114428;9875.851862221929;9915.52111972007;9963.800723985625;9992.20062394992;10263.281570290008;6934.879065389892;7114.608696521741;7160.57036577807;7309.963450182715;7320.414366850746;8163.877541836996;9593.808123837345;9620;9628.614855405705;10074.597016119273 21:13:15 INFO - PROCESS | 5620 | |3;mootools.html;1139.8233273842557;1367.705943222207;1381.3162484570132;1384.4462215113954;1385.099685204617;6769.390754832064;7642.808929776758;7804.141544430108;7856.567888766115;8009.239122283374;7873.330766884822;7929.6828126875;7950;7950.24472675095;7956.641951112199;2102.84228682849;2135.957280854382;2137.4976880433087;2140.176032227596;2141.3040761752413;1767.7436771668101;1819.0359109671829;2095.1200495791727;2147.6241657709916;2157.2126173946476;2128.4785227619254;2137.957240855182;2141.586552875103;2141.6466283063355;2143.2605751015876;2039.9286024989128;2067.607154640621;2081.6773400123006;2082.0422605601443;2086.2698055680544;2121.4378189779723;2124.7025416441556;2134.925277615276;2154.9030293636665;2156.1590979518;7807.375409967246;7930.405219608552;7950.24472675095;7952.801179970489;7971.402144839102;4572.611328037121;4716.811327546894;4718.905621887543;4719.669623126382;4726.550977657117;2760.986195069012;2827.137722994486;2966.896158634459;3018.818870867733;3042.8630711617807;1123.7134530694702;1129.7345123895877;1157.0974639780984;1183.0476466444509;1183.8520184976878;10581.788364232749;10634.893651063468;10647.520861561248;10658.62694805678;10659.467026648637;3905.433712111728;4123.9175216495505;4161.126163505698;4187.183499217624;4212.220739163265;9005.369624126248;9011.098890110936;9013.369064165445;9015.233705135137;9017.77455563603;4661.627069834405;4669.089527542097;4671.813127474863;4671.8364857229835;4673.883152921204;4120.2583534964;4158.625723684822;4227.112306415687;4318;4318.438602981592;3183.697548732867;3298.9835050825072;3322.883699070521;3346.347462244839;3350.1959529712694;1044.6709286574858;1157.907367410605;1180.3567055954525;1181.645506348092;1197.5329621447643;778.8948491953627;794.1026639896879;797.6171437709932;798.8562058829466;821.0926925747083;5019.799208031638;5061.620378471629;5068.619853511002;5115.283860259567;5235.764390602432;861.2507118806553;862.4868203418956;863.4819108534829;872.6204101215991;900.1943260781695;3269.623993240808;3294.1270563300745;3297.884574039897;3335.116194208537;3346.7322614190807;9189.759382483413;9395.812083758286;9410.247180225566;9414.95292523533;9470.573824177929;9310.674126405544;9354.345195836362;9446.149846513847;9452.527373631428;9464.905350946403;7300.379467745302;7327.633618319062;7359.116905971211;7366.926330736731;7370.078740157481;4614.353990441408;4680.227762419299;4708.270218116198;4742.691725037862;4752.239641657318;8356.039055508694;8357.498550087013;8489.193526614961;8570.100139485265;8588.785280368042;8318.958405207935;8639.04970453237;8730.956345218234;8738.475691458532;8751.474911505331;6378.075179099097;6559.0817285581015;6560.212774467094;6563.0811686362995;6564.343565643398;7247.420206383582;7261.128664560287;7286.417086633162;7286.7813965579;7327.853442931005;6559.311272316338;6568.211814582281;6582.275949645439;6588.769393071411;6642.269350371359;6192.442680158807;6240.4071613196675;6245.6252624842655;6253.28689508722;6274.435300822948;5753.280839895013;5790.073588225863;5850.034744267318;5850.736716847667;5854.356020837789;4388.605025547716;4432.401625780479;4499.167653984024;4514.32285157229;4651.906961860676;4458.799354029012;4491.708038977456;4572.794224259983;4577.793999269974;4640.512746161605 21:13:15 INFO - PROCESS | 5620 | |4;prototype.html;338.4060972992398;357.68345014662015;357.70668052197203;362.4490774023483;362.9908853387582;4931.3835770528685;4951.504849515047;4966.106100901836;4983.676061056029;5000.324956130926;4834.951650483494;4878.975605121969;4884;4884.682495637781;4931.211006239003;3630.673239408453;3767.8116094195316;3803.9809800950957;3834.33206555148;3850.459816073565;3790.2798468291076;3806.90482737931;3829.047185935179;3830.712696547756;3845.538535375759;3725.068732816796;3751.8931915085113;3761.153740408414;3775.2252094710366;3775.2826962876966;3649.251903359805;3753.774773513598;3760.480478079693;3768.246350729851;3771.5662698789442;3278.918027049319;3287.3589650018257;3296.8516416761304;3303.8182899940493;3313.287643156709;4713.811447542094;4716.221823399135;4744.454387745419;4751.3348131261655;4765.428148622153;4255.17024180285;4280.59334363235;4281.678874084456;4283.229018776619;4296.410328178563;4837.443693975203;5022.748862556858;5078.162103252959;5085.872853178663;5104.821331253425;3609.8375573099274;3610.772337405295;3616.1682812952904;3630.6732394084397;3640.5085313482605;5083.542481176675;5126.38483381989;5198.714070726109;5324.9733751331;5346.117890548055;3638.890833274953;3649.5620525537106;3652.61200743721;3660.2862441824122;3661.956342442391;3722.8696995605565;3734.9626503734617;3735.8879233623034;3736.9439458408146;3749;4071.42999980003;4187.72779769314;4270.402143699885;4291.570842915746;4303.677224208197;4782.115308667908;5026.597872170218;5083.847484575469;5106.106431374569;5114.462981386975;1233.6669099343308;1241.5158088345552;1243.6766440725476;1248.850137983448;1248.9875101248872;807.7415227127381;849.0957130655831;863.1799790199336;874.156439036333;875.9343049271203;4225.746455212636;4265.936010959838;4273.529911709709;4280.700350975402;4281.764502952367;5084.3644544431945;5085.694298705796;5087.659676548829;5101.362329708786;5133.717645529457;4176.976640723043;4210.136921930997;4231.280682284019;4292.411807631196;4304.139172165516;2541.8601976891077;2698.123109989261;2706.998410588056;2707.648005759239;2716.225875625477;4767.070421267819;4781;4798.160321943646;4808.182608956486;4808.783604737795;4909.7790599423115;5205.609579281569;5205.6616319939085;5208.557272631793;5221.216817477332;4737.218358970731;4752.52474752522;4762.690425122425;4768.47546769855;4784.473707892129;5056.797728090835;5062.873428164326;5068.983954547039;5075.4773567930215;5084.745762711776;4695.741734204652;4988.8752781180765;5007.624428167829;5037.370328708911;5040.36995375578;4703.200455922433;4780.709208513612;4800.3759511263315;4801.663883528259;4810.6187437218805;3896.298666240104;3939.763614183158;3975.1453437511072;4018.4976877890263;4028.8388464461095;5253.737313134405;5268.947310526845;5272.578193744568;5278.736063196901;5284.022455845681;4853.563179313879;5078.162103252922;5106.62121227274;5119.46245644202;5137.845864624067;5077.289179514797;5090.032893750175;5094.032133894548;5103.591712663052;5105.668131571436;5038.697678139323;5043.697378157322;5049.368828896388;5071.239314102767;5073.036123136592;4901.2893130496595;5077.543021128116;5129.615278854176;5132.486751324837;5160.406553246425;4978.103941290602;5014.724190169576;5018.623603229846;5024.246363045572;5026.371703537058 21:13:15 INFO - PROCESS | 5620 | |5;yui.html;594.3669991459093;596.4542443664047;694.0734119949867;703.4091363254867;705.3968856627583;11313.15151363648;12056.216345937508;12068.094892883026;12097.697557561065;12121.212121212126;11074.778504429907;11127.332360058383;11135;11206.159538034639;12039.939800300986;807.4590024683447;828.7099515169702;834.2741814621288;838.9748307550752;841.6212704283066;829.5810615639094;842.5492361586553;842.7724514381114;843.0094638799417;843.2158092973532;885.4996926736405;886.3485338276362;887.2857349833381;887.3034667785789;887.3477993674644;880.2122100719884;880.300161371711;880.330948479154;880.9075047120024;880.9295256379474;642.4250295985114;642.6112202117698;642.935706429358;643.3309358267396;643.5977514053717;13079.607611771662;13090.14914030585;13440.932795336059;13547.59357219285;13603.183808971395;12556.309402982832;13089.672758181026;13140.802887956688;13429.342754632047;13545.796813047813;10229.751127842039;10353.430561319125;10422.062014418663;10512.947435262851;10584.788304233873;3080.8305543195115;3113.688631136868;3138.764592655537;3196.7282780963415;3228.564143840575;58282;58588;59474.99999999957;59736.70131649357;61350.38649613536;9418.670346537838;9566.9043309566;9657.179139773058;9964.352317099365;9972;17065.232064557124;17078.146092695562;17103.401380951607;17122.914385427994;17170.22733976949;6268.185135932391;6341.460975817107;6541.901871471931;6543.083968244449;6561.967190164019;3478.9304213916084;3491.965080349215;3510.57873055235;3594.6225646307284;3623.166671665532;2904.695007024274;2973.494505933953;2985.5372417275357;2990.4467673480476;2993.4312480629046;521.9895602087937;527.9683219006872;535.1357557544537;538.9083855744454;550.3560833824436;613.2763339259716;642.6208536963214;645.6932956845554;648.6529706606942;649.909012738217;7911.683532658629;8018.438709290293;8441.944756905386;8475.618597163142;9035.683751068682;613.6134235431649;630.1933525087895;634.1597383466925;637.1239545624766;642.4057746584391;3998.4602078719;4139.606737360006;4229.513605935295;4250.362445633118;4271.295236286041;61395.38604613897;62680.37319626745;63537;63622;65602.67198664072;52941.47058529365;53795.731021345426;54023.45976540263;54290.72854635781;55472;43913.78043109764;46992.7650361746;47026.76486617545;49029.50970490249;49648.25527617161;8090.959545202236;8249.092599814136;8256.339492840438;8289.502629842229;8534.573271336532;28376.85811570929;29474;34497.13757156091;35484.29031419409;35616.82191589129;40916.59083409128;41007.58992410038;41246.58753412547;41278.79360603178;42449.93875153146;11139.944300278446;11354.205205635526;11431.59989400367;11735.41322933867;11750.414908720068;12890.548830790878;12925.935370323088;13188.208707477583;13344.266065366499;13487.595372138852;11130;11305.321680699184;11308.273365216963;11320.660380188607;12088.456019478968;12280.140390172959;12346.876531234573;12460.813087803508;13041.304630397988;13143.408546615616;11007.83488247693;11242.100631949588;11331.263467874562;11402.20184587104;11495;9176.6788162414;9252.95373523155;9341.813163736551;9363.344565880323;9564.282678798978;9015.864762028707;9017.639294428413;9074.682386116454;9077.909220907706;9103.681371151977 21:13:15 INFO - PROCESS | 5620 | __end_tp_report 21:13:15 INFO - PROCESS | 5620 | __start_cc_report 21:13:15 INFO - PROCESS | 5620 | _x_x_mozilla_cycle_collect,1527 21:13:15 INFO - PROCESS | 5620 | __end_cc_report 21:13:15 INFO - PROCESS | 5620 | __startTimestamp1487653995159__endTimestamp 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | ------- Summary: start ------- 21:13:15 INFO - PROCESS | 5620 | Number of tests: 6 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#0] dojo.html Cycles:165 Average:4696.16 Median:4809.04 stddev:518.39 (10.8%) stddev-sans-first:490.46 21:13:15 INFO - PROCESS | 5620 | Values: 2497.8 2574.6 2583.7 2620.0 2625.8 4612.4 4644.2 4781.9 4817.0 4850.7 4692.9 4740.9 4742.5 4763.2 4802.7 4324.9 4340.3 4379.9 4421.3 4438.6 4377.4 4444.8 4457.6 4470.3 4505.9 4189.2 4221.9 4225.0 4265.4 4286.4 4256.8 4271.9 4290.9 4299.6 4304.1 4278.9 4295.8 4337.8 4354.4 4364.1 4681.3 4703.1 4737.8 4744.1 4795.9 4909.8 4936.3 4936.6 5070.9 5084.5 5028.5 5037.1 5040.2 5043.5 5044.3 4711.9 4715.7 4722.7 4784.0 4807.5 5312.8 5347.7 5350.2 5350.9 5357.8 4821.5 4897.1 4907.0 4977.9 4986.1 5043.3 5048.1 5049.3 5051.5 5051.6 5020.7 5031.3 5034.6 5086.0 5104.7 5160.1 5162.6 5166.4 5170.9 5219.6 4145.4 4163.9 4327.5 4388.8 4391.0 3706.4 3789.2 3810.0 3827.5 3832.3 4396.4 4399.0 4403.6 4405.4 4444.3 4912.2 4941.7 5206.4 5215.9 5216.4 4218.8 4260.6 4269.1 4290.5 4291.6 4593.6 4593.8 4628.5 4630.3 4639.1 4809.0 4816.1 4817.3 4821.1 4865.2 4894.8 4920.5 5130.2 5134.1 5183.4 4687.8 4702.2 4712.3 4812.9 4813.7 5104.7 5104.8 5147.2 5155.0 5155.4 5137.1 5174.3 5180.8 5181.6 5191.0 4806.5 4835.3 4959.6 5029.4 5048.6 4969.5 5081.3 5094.6 5097.4 5100.7 4954.7 4970.9 4971.1 4971.1 5023.8 5043.0 5046.2 5053.9 5057.7 5102.6 4663.3 4690.6 4716.3 4789.5 4797.2 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#1] ext.html Cycles:180 Average:40751.40 Median:34402.23 stddev:67713.28 (196.8%) stddev-sans-first:67835.98 21:13:15 INFO - PROCESS | 5620 | Values: 558.6 560.3 568.1 573.0 631.9 27003.2 27583.9 38000.6 39098.0 39487.8 39288.4 39776.4 39782.8 39795.2 39809.8 7380.5 7534.5 7549.5 7555.1 7559.0 6995.7 7203.7 9628.8 9852.6 9871.9 9920.9 9975.0 9985.1 9991.9 10008.4 9458.8 9508.5 9511.1 9514.3 9515.5 9550.6 9624.9 9686.8 9693.4 9695.8 37135.4 37279.6 37521.4 37595.8 37629.4 3451.7 3477.8 3478.1 3497.6 3539.7 26973.6 27477.0 27484.9 27607.2 27614.7 3124.9 3270.9 3365.1 3519.9 3524.2 213966.0 214415.0 215008.0 215085.9 215874.0 51910.5 52187.0 52517.5 52572.5 52601.7 56059.2 56869.4 56898.7 56900.4 57041.2 13088.1 13360.0 13368.1 13398.5 13413.1 9937.8 10124.3 10146.6 10199.1 10202.5 16700.2 16825.7 16916.3 16929.4 16984.7 5696.3 5993.6 6001.2 6016.2 6073.9 742.4 766.1 772.3 779.1 791.0 25530.4 26058.9 26274.6 26476.7 26481.1 876.3 898.9 904.4 915.5 924.5 13393.3 13532.9 13806.4 14115.4 14150.4 293060.0 295294.0 295801.0 295898.0 296134.0 48137.8 48296.8 48323.8 48449.0 48563.5 1142.3 1175.8 1183.9 1186.1 1197.8 24218.2 24895.3 25831.2 26023.9 26080.9 40301.8 40848.8 40858.0 40879.0 40899.6 258563.0 260341.0 260371.0 260495.0 261101.0 20177.6 20816.5 20904.2 34037.7 34665.8 33876.7 34381.3 34423.1 34645.8 34968.7 28630.3 28823.4 28970.7 29324.9 29999.6 28738.6 28989.9 29043.7 29087.7 29407.8 26762.9 26991.0 26994.9 27020.3 27100.5 24703.4 25179.5 26441.9 26588.9 27000.0 20572.6 20595.3 20773.4 20847.6 20882.7 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#2] jquery.html Cycles:180 Average:26331.73 Median:4947.00 stddev:71701.85 (1449.4%) stddev-sans-first:71876.43 21:13:15 INFO - PROCESS | 5620 | Values: 341.1 362.3 399.7 399.9 402.0 12376.9 12963.0 13408.2 18413.0 18452.5 17810.8 17899.3 18252.5 18416.5 18439.4 5239.0 5392.1 5428.5 5429.8 5432.3 5184.1 5284.0 5312.8 5330.4 5412.6 5277.7 5283.0 5283.7 5285.3 5319.2 5071.9 5240.8 5247.6 5279.6 5327.7 4811.6 4811.8 4817.3 4819.5 4822.1 21000.8 21030.4 21565.9 21681.9 21912.8 2053.9 2229.7 2244.6 2487.3 2629.6 8550.7 8673.9 8813.7 8936.2 11652.5 1562.7 1582.9 1602.7 1615.6 1637.5 73735.6 73823.3 74609.3 74770.0 75088.6 10132.5 10231.8 10392.2 10694.8 10891.4 15250.2 15301.0 15321.6 15345.7 15362.2 7158.7 7279.3 7391.6 7490.7 7505.9 6532.6 6550.5 6760.5 6790.7 6799.4 4316.4 4341.2 4533.5 4603.6 4768.3 1750.6 1908.0 1945.8 1953.4 2010.7 778.8 800.3 802.5 835.3 839.1 16585.1 16765.1 17318.1 17411.6 17588.2 780.1 787.5 788.7 792.5 805.4 9012.0 9416.9 9815.8 10274.6 10280.9 427144.0 427265.0 427611.0 437198.0 443686.0 89984.6 92458.0 92958.5 93231.5 93325.0 937.6 957.3 980.2 980.6 982.8 15576.3 15773.4 16370.7 16386.4 16688.4 39372.0 40338.6 40849.0 40937.0 41044.2 54886.7 55490.4 55739.4 55949.7 56583.0 6814.1 7004.5 7025.7 7179.8 7479.1 11212.3 11860.0 12216.2 12453.3 12565.6 9846.8 9957.9 9960.0 10019.8 10262.6 9700.0 10180.0 10294.2 10597.5 10696.6 9875.9 9915.5 9963.8 9992.2 10263.3 6934.9 7114.6 7160.6 7310.0 7320.4 8163.9 9593.8 9620.0 9628.6 10074.6 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#3] mootools.html Cycles:180 Average:5018.97 Median:4719.29 stddev:2826.12 (59.9%) stddev-sans-first:2819.01 21:13:15 INFO - PROCESS | 5620 | Values: 1139.8 1367.7 1381.3 1384.4 1385.1 6769.4 7642.8 7804.1 7856.6 8009.2 7873.3 7929.7 7950.0 7950.2 7956.6 2102.8 2136.0 2137.5 2140.2 2141.3 1767.7 1819.0 2095.1 2147.6 2157.2 2128.5 2138.0 2141.6 2141.6 2143.3 2039.9 2067.6 2081.7 2082.0 2086.3 2121.4 2124.7 2134.9 2154.9 2156.2 7807.4 7930.4 7950.2 7952.8 7971.4 4572.6 4716.8 4718.9 4719.7 4726.6 2761.0 2827.1 2966.9 3018.8 3042.9 1123.7 1129.7 1157.1 1183.0 1183.9 10581.8 10634.9 10647.5 10658.6 10659.5 3905.4 4123.9 4161.1 4187.2 4212.2 9005.4 9011.1 9013.4 9015.2 9017.8 4661.6 4669.1 4671.8 4671.8 4673.9 4120.3 4158.6 4227.1 4318.0 4318.4 3183.7 3299.0 3322.9 3346.3 3350.2 1044.7 1157.9 1180.4 1181.6 1197.5 778.9 794.1 797.6 798.9 821.1 5019.8 5061.6 5068.6 5115.3 5235.8 861.3 862.5 863.5 872.6 900.2 3269.6 3294.1 3297.9 3335.1 3346.7 9189.8 9395.8 9410.2 9415.0 9470.6 9310.7 9354.3 9446.1 9452.5 9464.9 7300.4 7327.6 7359.1 7366.9 7370.1 4614.4 4680.2 4708.3 4742.7 4752.2 8356.0 8357.5 8489.2 8570.1 8588.8 8319.0 8639.0 8731.0 8738.5 8751.5 6378.1 6559.1 6560.2 6563.1 6564.3 7247.4 7261.1 7286.4 7286.8 7327.9 6559.3 6568.2 6582.3 6588.8 6642.3 6192.4 6240.4 6245.6 6253.3 6274.4 5753.3 5790.1 5850.0 5850.7 5854.4 4388.6 4432.4 4499.2 4514.3 4651.9 4458.8 4491.7 4572.8 4577.8 4640.5 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#4] prototype.html Cycles:180 Average:4181.80 Median:4767.77 stddev:1207.93 (25.3%) stddev-sans-first:1176.37 21:13:15 INFO - PROCESS | 5620 | Values: 338.4 357.7 357.7 362.4 363.0 4931.4 4951.5 4966.1 4983.7 5000.3 4835.0 4879.0 4884.0 4884.7 4931.2 3630.7 3767.8 3804.0 3834.3 3850.5 3790.3 3806.9 3829.0 3830.7 3845.5 3725.1 3751.9 3761.2 3775.2 3775.3 3649.3 3753.8 3760.5 3768.2 3771.6 3278.9 3287.4 3296.9 3303.8 3313.3 4713.8 4716.2 4744.5 4751.3 4765.4 4255.2 4280.6 4281.7 4283.2 4296.4 4837.4 5022.7 5078.2 5085.9 5104.8 3609.8 3610.8 3616.2 3630.7 3640.5 5083.5 5126.4 5198.7 5325.0 5346.1 3638.9 3649.6 3652.6 3660.3 3662.0 3722.9 3735.0 3735.9 3736.9 3749.0 4071.4 4187.7 4270.4 4291.6 4303.7 4782.1 5026.6 5083.8 5106.1 5114.5 1233.7 1241.5 1243.7 1248.9 1249.0 807.7 849.1 863.2 874.2 875.9 4225.7 4265.9 4273.5 4280.7 4281.8 5084.4 5085.7 5087.7 5101.4 5133.7 4177.0 4210.1 4231.3 4292.4 4304.1 2541.9 2698.1 2707.0 2707.6 2716.2 4767.1 4781.0 4798.2 4808.2 4808.8 4909.8 5205.6 5205.7 5208.6 5221.2 4737.2 4752.5 4762.7 4768.5 4784.5 5056.8 5062.9 5069.0 5075.5 5084.7 4695.7 4988.9 5007.6 5037.4 5040.4 4703.2 4780.7 4800.4 4801.7 4810.6 3896.3 3939.8 3975.1 4018.5 4028.8 5253.7 5268.9 5272.6 5278.7 5284.0 4853.6 5078.2 5106.6 5119.5 5137.8 5077.3 5090.0 5094.0 5103.6 5105.7 5038.7 5043.7 5049.4 5071.2 5073.0 4901.3 5077.5 5129.6 5132.5 5160.4 4978.1 5014.7 5018.6 5024.2 5026.4 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - PROCESS | 5620 | [#5] yui.html Cycles:180 Average:14090.90 Median:524.98 stddev:17220.97 (3280.3%) stddev-sans-first:17239.46 21:13:15 INFO - PROCESS | 5620 | Values: 594.4 596.5 694.1 703.4 705.4 11313.2 12056.2 12068.1 12097.7 12121.2 11074.8 11127.3 11135.0 11206.2 12039.9 807.5 828.7 834.3 839.0 841.6 829.6 842.5 842.8 843.0 843.2 885.5 886.3 887.3 887.3 887.3 880.2 880.3 880.3 880.9 880.9 642.4 642.6 642.9 643.3 643.6 13079.6 13090.1 13440.9 13547.6 13603.2 12556.3 13089.7 13140.8 13429.3 13545.8 10229.8 10353.4 10422.1 10512.9 10584.8 3080.8 3113.7 3138.8 3196.7 3228.6 58282.0 58588.0 59475.0 59736.7 61350.4 9418.7 9566.9 9657.2 9964.4 9972.0 17065.2 17078.1 17103.4 17122.9 17170.2 6268.2 6341.5 6541.9 6543.1 6562.0 3478.9 3492.0 3510.6 3594.6 3623.2 2904.7 2973.5 2985.5 2990.4 2993.4 522.0 528.0 535.1 538.9 550.4 613.3 642.6 645.7 648.7 649.9 7911.7 8018.4 8441.9 8475.6 9035.7 613.6 630.2 634.2 637.1 642.4 3998.5 4139.6 4229.5 4250.4 4271.3 61395.4 62680.4 63537.0 63622.0 65602.7 52941.5 53795.7 54023.5 54290.7 55472.0 43913.8 46992.8 47026.8 49029.5 49648.3 8091.0 8249.1 8256.3 8289.5 8534.6 28376.9 29474.0 34497.1 35484.3 35616.8 40916.6 41007.6 41246.6 41278.8 42449.9 11139.9 11354.2 11431.6 11735.4 11750.4 12890.5 12925.9 13188.2 13344.3 13487.6 11130.0 11305.3 11308.3 11320.7 12088.5 12280.1 12346.9 12460.8 13041.3 13143.4 11007.8 11242.1 11331.3 11402.2 11495.0 9176.7 9253.0 9341.8 9363.3 9564.3 9015.9 9017.6 9074.7 9077.9 9103.7 21:13:15 INFO - PROCESS | 5620 | -------- Summary: end -------- 21:13:15 INFO - PROCESS | 5620 | 21:13:15 INFO - TEST-INFO | 5620: exit 0 21:13:16 INFO - TEST-OK | dromaeo_css | took 1122629ms 21:13:16 INFO - TEST-START | kraken 21:13:16 INFO - operating with platform_type : linux_ 21:13:16 INFO - Initialising browser for kraken test... 21:13:16 INFO - TEST-INFO | started process 5947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppgcKcJ/profile http://localhost:39135/getInfo.html) 21:13:18 INFO - PROCESS | 5947 | Iterating over profile /tmp/tmppgcKcJ/profile 21:13:19 INFO - PROCESS | 5947 | __metrics Screen width/height:1600/1200 21:13:19 INFO - PROCESS | 5947 | colorDepth:24 21:13:19 INFO - PROCESS | 5947 | Browser inner width/height: 1024/697 21:13:19 INFO - PROCESS | 5947 | __metrics 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/mimeTypes.rdf = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/key3.db = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/bookmarkbackups = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/webappsstore.sqlite-wal = Mon Feb 20 2017 21:13:20 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/prefs.js = Mon Feb 20 2017 20:53:52 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/extensions.ini = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/webappsstore.sqlite = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/safebrowsing-backup = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | Stat failure 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/places.sqlite-shm = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/extensions = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/localstore.rdf = Mon Feb 20 2017 20:53:52 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/addons.json = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/startupCache = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/safebrowsing = Mon Feb 20 2017 21:13:20 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/storage.sqlite = Mon Feb 20 2017 21:13:20 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/extensions.json = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/compatibility.ini = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/crashes = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | Stat failure 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/user.js = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/places.sqlite-wal = Mon Feb 20 2017 21:13:20 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/places.sqlite = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/.parentlock = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/frequencyCap.json = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/cert8.db = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/cookies.sqlite = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/gmp = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/pluginreg.dat = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/thumbnails = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/blocklist.xml = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/content-prefs.sqlite = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/permissions.sqlite = Mon Feb 20 2017 21:13:17 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/directoryLinks.json = Mon Feb 20 2017 21:13:18 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/webappsstore.sqlite-shm = Mon Feb 20 2017 21:13:20 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/secmod.db = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/cache2 = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/minidumps = Mon Feb 20 2017 21:13:16 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | No birth date. Using mtime. 21:13:20 INFO - PROCESS | 5947 | Using date: /tmp/tmppgcKcJ/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:13:19 GMT-0800 (PST) 21:13:20 INFO - PROCESS | 5947 | 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" 21:13:20 INFO - PROCESS | 5947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:13:21 INFO - TEST-INFO | 5947: exit 0 21:13:21 INFO - Browser initialized. 21:13:21 INFO - Running cycle 1/1 for kraken test... 21:13:21 INFO - TEST-INFO | started process 6034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppgcKcJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:13:33 INFO - PROCESS | 6034 | RSS: Main: 147746816 21:13:33 INFO - PROCESS | 6034 | 21:13:36 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39135/tests/kraken/driver.html?testName=audio-beat-detection) 21:13:37 INFO - PROCESS | 6034 | RSS: Main: 202760192 21:13:37 INFO - PROCESS | 6034 | 21:13:40 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39135/tests/kraken/driver.html?testName=audio-dft) 21:13:40 INFO - PROCESS | 6034 | RSS: Main: 181399552 21:13:40 INFO - PROCESS | 6034 | 21:13:44 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39135/tests/kraken/driver.html?testName=audio-fft) 21:13:44 INFO - PROCESS | 6034 | RSS: Main: 264577024 21:13:44 INFO - PROCESS | 6034 | 21:13:46 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39135/tests/kraken/driver.html?testName=audio-oscillator) 21:13:47 INFO - PROCESS | 6034 | RSS: Main: 175185920 21:13:47 INFO - PROCESS | 6034 | 21:13:49 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39135/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:13:50 INFO - PROCESS | 6034 | RSS: Main: 178540544 21:13:50 INFO - PROCESS | 6034 | 21:13:53 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39135/tests/kraken/driver.html?testName=imaging-darkroom) 21:13:53 INFO - PROCESS | 6034 | RSS: Main: 217206784 21:13:53 INFO - PROCESS | 6034 | 21:13:57 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39135/tests/kraken/driver.html?testName=imaging-desaturate) 21:13:58 INFO - PROCESS | 6034 | RSS: Main: 211496960 21:13:58 INFO - PROCESS | 6034 | 21:14:00 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39135/tests/kraken/driver.html?testName=json-parse-financial) 21:14:01 INFO - PROCESS | 6034 | RSS: Main: 213200896 21:14:01 INFO - PROCESS | 6034 | 21:14:03 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39135/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:14:03 INFO - PROCESS | 6034 | RSS: Main: 177254400 21:14:03 INFO - PROCESS | 6034 | 21:14:05 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:14:06 INFO - PROCESS | 6034 | RSS: Main: 184467456 21:14:06 INFO - PROCESS | 6034 | 21:14:08 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:14:09 INFO - PROCESS | 6034 | RSS: Main: 193073152 21:14:09 INFO - PROCESS | 6034 | 21:14:12 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:14:12 INFO - PROCESS | 6034 | RSS: Main: 202833920 21:14:12 INFO - PROCESS | 6034 | 21:14:15 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:14:16 INFO - PROCESS | 6034 | RSS: Main: 198520832 21:14:16 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | Cycle 1(1): loaded http://localhost:39135/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39135/tests/kraken/driver.html?testName=ai-astar) 21:14:18 INFO - PROCESS | 6034 | RSS: Main: 250220544 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | __start_tp_report 21:14:18 INFO - PROCESS | 6034 | _x_x_mozilla_page_load 21:14:18 INFO - PROCESS | 6034 | _x_x_mozilla_page_load_details 21:14:18 INFO - PROCESS | 6034 | |i|pagename|runs| 21:14:18 INFO - PROCESS | 6034 | |0;ai-astar;109;116;115;116;116;117;115;114;115;114 21:14:18 INFO - PROCESS | 6034 | |1;audio-beat-detection;158;126;125;173;131;132;128;126;147;129 21:14:18 INFO - PROCESS | 6034 | |2;audio-dft;160;162;162;166;164;164;162;162;163;162 21:14:18 INFO - PROCESS | 6034 | |3;audio-fft;76;76;76;117;77;78;78;78;94;76 21:14:18 INFO - PROCESS | 6034 | |4;audio-oscillator;83;85;121;85;85;84;106;84;85;114 21:14:18 INFO - PROCESS | 6034 | |5;imaging-gaussian-blur;114;113;113;113;114;114;114;113;114;113 21:14:18 INFO - PROCESS | 6034 | |6;imaging-darkroom;149;151;148;149;149;149;148;150;148;150 21:14:18 INFO - PROCESS | 6034 | |7;imaging-desaturate;95;95;95;96;95;96;96;97;96;95 21:14:18 INFO - PROCESS | 6034 | |8;json-parse-financial;61;62;62;61;63;64;66;63;62;62 21:14:18 INFO - PROCESS | 6034 | |9;json-stringify-tinderbox;65;65;65;65;66;65;65;64;66;66 21:14:18 INFO - PROCESS | 6034 | |10;stanford-crypto-aes;88;89;87;87;88;88;89;88;87;88 21:14:18 INFO - PROCESS | 6034 | |11;stanford-crypto-ccm;136;146;137;137;137;144;136;139;175;155 21:14:18 INFO - PROCESS | 6034 | |12;stanford-crypto-pbkdf2;193;188;184;186;186;188;182;183;185;187 21:14:18 INFO - PROCESS | 6034 | |13;stanford-crypto-sha256-iterative;75;71;73;72;72;70;71;77;70;71 21:14:18 INFO - PROCESS | 6034 | __end_tp_report 21:14:18 INFO - PROCESS | 6034 | __start_cc_report 21:14:18 INFO - PROCESS | 6034 | _x_x_mozilla_cycle_collect,1993 21:14:18 INFO - PROCESS | 6034 | __end_cc_report 21:14:18 INFO - PROCESS | 6034 | __startTimestamp1487654058674__endTimestamp 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | ------- Summary: start ------- 21:14:18 INFO - PROCESS | 6034 | Number of tests: 14 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.50 stddev:2.21 (1.9%) stddev-sans-first:1.00 21:14:18 INFO - PROCESS | 6034 | Values: 109.0 116.0 115.0 116.0 116.0 117.0 115.0 114.0 115.0 114.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:131.50 stddev:16.42 (12.5%) stddev-sans-first:15.65 21:14:18 INFO - PROCESS | 6034 | Values: 158.0 126.0 125.0 173.0 131.0 132.0 128.0 126.0 147.0 129.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.50 stddev:1.64 (1.0%) stddev-sans-first:1.41 21:14:18 INFO - PROCESS | 6034 | Values: 160.0 162.0 162.0 166.0 164.0 164.0 162.0 162.0 163.0 162.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#3] audio-fft Cycles:10 Average:82.60 Median:77.50 stddev:13.26 (17.1%) stddev-sans-first:13.85 21:14:18 INFO - PROCESS | 6034 | Values: 76.0 76.0 76.0 117.0 77.0 78.0 78.0 78.0 94.0 76.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:84.50 stddev:14.57 (17.2%) stddev-sans-first:14.98 21:14:18 INFO - PROCESS | 6034 | Values: 83.0 85.0 121.0 85.0 85.0 84.0 106.0 84.0 85.0 114.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:14:18 INFO - PROCESS | 6034 | Values: 114.0 113.0 113.0 113.0 114.0 114.0 114.0 113.0 114.0 113.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 21:14:18 INFO - PROCESS | 6034 | Values: 149.0 151.0 148.0 149.0 149.0 149.0 148.0 150.0 148.0 150.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 21:14:18 INFO - PROCESS | 6034 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 95.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.50 stddev:1.51 (2.4%) stddev-sans-first:1.48 21:14:18 INFO - PROCESS | 6034 | Values: 61.0 62.0 62.0 61.0 63.0 64.0 66.0 63.0 62.0 62.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 21:14:18 INFO - PROCESS | 6034 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 65.0 64.0 66.0 66.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 21:14:18 INFO - PROCESS | 6034 | Values: 88.0 89.0 87.0 87.0 88.0 88.0 89.0 88.0 87.0 88.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:141.50 stddev:12.41 (8.8%) stddev-sans-first:12.80 21:14:18 INFO - PROCESS | 6034 | Values: 136.0 146.0 137.0 137.0 137.0 144.0 136.0 139.0 175.0 155.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.20 Median:186.50 stddev:3.12 (1.7%) stddev-sans-first:2.13 21:14:18 INFO - PROCESS | 6034 | Values: 193.0 188.0 184.0 186.0 186.0 188.0 182.0 183.0 185.0 187.0 21:14:18 INFO - PROCESS | 6034 | 21:14:18 INFO - PROCESS | 6034 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.00 stddev:2.25 (3.1%) stddev-sans-first:2.15 21:14:18 INFO - PROCESS | 6034 | Values: 75.0 71.0 73.0 72.0 72.0 70.0 71.0 77.0 70.0 71.0 21:14:18 INFO - PROCESS | 6034 | -------- Summary: end -------- 21:14:18 INFO - PROCESS | 6034 | 21:14:19 INFO - TEST-INFO | 6034: exit 0 21:14:19 INFO - TEST-OK | kraken | took 63804ms 21:14:19 INFO - SUITE-END | took 1186s 21:14:19 INFO - Completed test suite (00:19:46) 21:14:19 INFO - javascript benchmark 21:14:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6277.819031385898, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2497.812664050196, 2574.562324404851, 2583.6899572051357, 2620.004398328636, 2625.8293210941274, 4612.354270402138, 4644.210484217682, 4781.856544303672, 4816.975915120423, 4850.708957462551, 4692.929606055912, 4740.928886066703, 4742.549457801512, 4763.190257656198, 4802.687825291354, 4324.870253892388, 4340.262155433568, 4379.934300985487, 4421.270490369086, 4438.556144385568, 4377.387165796807, 4444.844430444935, 4457.59881610655, 4470.307102399133, 4505.932411013838, 4189.245935731567, 4221.852235171754, 4224.957750422487, 4265.360195970598, 4286.442762440894, 4256.808443620044, 4271.9359209611885, 4290.914181716348, 4299.613034826849, 4304.139172165579, 4278.935815962763, 4295.763732994684, 4337.783110844477, 4354.412154359153, 4364.148990946766, 4681.274402467623, 4703.059388122355, 4737.810487580493, 4744.051189762062, 4795.928061079087, 4909.828156014558, 4936.308916751657, 4936.555709986083, 5070.898582028376, 5084.51697088776, 5028.472010438924, 5037.0681423936685, 5040.16837221858, 5043.5460808527405, 5044.268581055726, 4711.858644240679, 4715.717056976592, 4722.716637001791, 4784.043191361672, 4807.543283388072, 5312.787488500494, 5347.705876176848, 5350.197470379474, 5350.91973620396, 5357.785688572413, 4821.54195351441, 4897.094037603055, 4907.043126590281, 4977.925331120036, 4986.077575648516, 5043.3191519145175, 5048.1418158913075, 5049.293098966147, 5051.4695956924725, 5051.570616497565, 5020.698758074527, 5031.270465782441, 5034.647574669812, 5086.0, 5104.744762761847, 5160.071187186342, 5162.586993040548, 5166.4058633257655, 5170.870728231825, 5219.634625576249, 4145.3781932710335, 4163.937540936889, 4327.545607711145, 4388.780560971939, 4390.978045109754, 3706.4069748840056, 3789.1663833956486, 3809.961900381016, 3827.5215598050245, 3832.329342365075, 4396.3625274335045, 4398.956010439854, 4403.647708183337, 4405.3612226227015, 4444.266695995125, 4912.238603016538, 4941.728204948762, 5206.401263854629, 5215.921761173586, 5216.439446711071, 4218.78906054696, 4260.637845783143, 4269.103488267437, 4290.528041915387, 4291.592298731678, 4593.63250939924, 4593.793279302439, 4628.467726211461, 4630.259158534618, 4639.095376401637, 4809.038192361471, 4816.109019831342, 4817.349657796293, 4821.083994041121, 4865.172920603435, 4894.755262236945, 4920.45874953762, 5130.230465430066, 5134.10153223177, 5183.352080989876, 4687.835925742583, 4702.177119004092, 4712.316714076508, 4812.927806082982, 4813.663043586916, 5104.668196567211, 5104.846854594368, 5147.227915812508, 5154.9690061987, 5155.407128180307, 5137.075326441276, 5174.275601415729, 5180.818671346485, 5181.611379146504, 5190.974045129901, 4806.543378379189, 4835.2505361669, 4959.553640172402, 5029.421616514148, 5048.621353398583, 4969.503049695001, 5081.33942587476, 5094.643374963865, 5097.388313402415, 5100.719460429712, 4954.702717836942, 4970.850874473772, 4971.080350135237, 4971.080350135237, 5023.824166154167, 5042.974785126197, 5046.21783623539, 5053.8736531587, 5057.721825299496, 5102.642815003062, 4663.300504924288, 4690.648201384978, 4716.339712440193, 4789.544993225638, 4797.18447863857], "value": 4661.343509772489, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.6033915919696, 560.3332034878495, 568.0768750779982, 572.9799457019005, 631.8546734251122, 27003.189904302853, 27583.862080689592, 38000.61999380009, 39098.0, 39487.80256098723, 39288.41067383984, 39776.40335394971, 39782.80108599453, 39795.20409591807, 39809.800950995275, 7380.483366164371, 7534.472586918918, 7549.509281896687, 7555.093388793325, 7558.959081048114, 6995.685194166249, 7203.747868824592, 9628.759281017961, 9852.605895764162, 9871.901280987206, 9920.900790992106, 9975.0, 9985.101340879319, 9991.90008099917, 10008.399496030224, 9458.763530911714, 9508.47703376314, 9511.143997040299, 9514.333996620204, 9515.476648784314, 9550.61797528098, 9624.855627165598, 9686.806263874754, 9693.369930954465, 9695.757606059906, 37135.44296835522, 37279.62720372788, 37521.43717844234, 37595.81202093972, 37629.43555846637, 3451.7238620910266, 3477.7565570410084, 3478.113081164287, 3497.5802903651725, 3539.681428671406, 26973.595396069075, 27477.038303659276, 27484.862575687195, 27607.171784846487, 27614.723852761417, 3124.8906288280027, 3270.9018729438153, 3365.125067482448, 3519.8768043118366, 3524.18943642961, 213966.0, 214415.0, 215008.0, 215085.9245703777, 215874.0, 51910.480895190565, 52187.0, 52517.47482525202, 52572.474275256754, 52601.7369913148, 56059.15911261334, 56869.43130568724, 56898.7155064222, 56900.43099569034, 57041.169295982516, 13088.08383413171, 13360.0, 13368.064235503616, 13398.464061437433, 13413.0610857241, 9937.751556211153, 10124.291299608956, 10146.594136234615, 10199.0820826126, 10202.531139412635, 16700.164991750364, 16825.747613785803, 16916.32334706598, 16929.42905415531, 16984.660306793794, 5696.3449203341315, 5993.580449368503, 6001.2498437695285, 6016.157737916695, 6073.878522429527, 742.4468770765814, 766.0960067120851, 772.320358084893, 779.1429427629562, 790.9644066017044, 25530.361740956254, 26058.869705651352, 26274.6058809118, 26476.735232647814, 26481.07316243922, 876.3471213945651, 898.8606765951288, 904.3895370624801, 915.5193523400135, 924.4684306523776, 13393.263370514715, 13532.86467135316, 13806.378712957941, 14115.435382584581, 14150.36323365451, 293060.0, 295294.0, 295801.0, 295898.0, 296134.0, 48137.75931120322, 48296.75851620719, 48323.75838120857, 48449.0, 48563.51436485661, 1142.3488611491537, 1175.788358095551, 1183.9348835813942, 1186.0748616079472, 1197.7963746163173, 24218.20830153288, 24895.253142405756, 25831.225063248134, 26023.869880650476, 26080.8695956519, 40301.79849100853, 40848.79575602103, 40858.0, 40879.0, 40899.59100409077, 258563.0, 260341.0, 260371.0, 260495.0, 261101.0, 20177.596448070664, 20816.479588009814, 20904.16383344649, 34037.65962340444, 34665.82667086648, 33876.66123338834, 34381.31237375289, 34423.13942151366, 34645.826770865984, 34968.65031349654, 28630.284242894093, 28823.423531529672, 28970.7102928968, 29324.853375732986, 29999.55000674948, 28738.568921466613, 28989.85505072461, 29043.709562904947, 29087.7091229085, 29407.823687053133, 26762.929482821248, 26991.0, 26994.86502567553, 27020.32449188786, 27100.457990839677, 24703.38241543976, 25179.49641007133, 26441.867790660923, 26588.884556551715, 27000.0, 20572.588548228654, 20595.279165229746, 20773.376798696063, 20847.583048339253, 20882.68675969892], "value": 16462.013203035884, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.12161184948746, 362.3351150638578, 399.71819866993764, 399.94800675912126, 401.97295908952634, 12376.876231237686, 12963.027772917034, 13408.195508269511, 18412.987285699313, 18452.538686532844, 17810.821891781077, 17899.28402863884, 18252.543686407847, 18416.53958651031, 18439.354622588213, 5238.973805130979, 5392.110301800199, 5428.538574221196, 5429.837104886859, 5432.320959880015, 5184.11869982104, 5284.0, 5312.76092575835, 5330.387005494379, 5412.5940554458575, 5277.70972596507, 5283.049051170788, 5283.682979021251, 5285.339332583427, 5319.228711836781, 5071.949280507185, 5240.842774716764, 5247.553957913582, 5279.577633789288, 5327.733613319358, 4811.639127065484, 4811.8075276988875, 4817.349657796188, 4819.5180481951875, 4822.059698358821, 21000.789992100188, 21030.369088927357, 21565.892170539046, 21681.891590542105, 21912.780872191233, 2053.917843286267, 2229.6989906362596, 2244.6408574628144, 2487.2786891801356, 2629.6318515407856, 8550.70072547464, 8673.913260867374, 8813.691520796805, 8936.151065648754, 11652.533898644044, 1562.7187106320857, 1582.8733701303984, 1602.7195240832807, 1615.5961009747564, 1637.5333030086367, 73735.63132184304, 73823.26176738218, 74609.25390746078, 74770.0, 75088.62455687742, 10132.49337533135, 10231.846522302172, 10392.22058345612, 10694.786104277871, 10891.400972946563, 15250.23748812555, 15301.0, 15321.6169595761, 15345.693086138215, 15362.155081470626, 7158.749443769547, 7279.344858962613, 7391.556506609621, 7490.737824176128, 7505.924940750632, 6532.640704761188, 6550.541462097703, 6760.492963027793, 6790.694418751168, 6799.388055075067, 4316.395704601358, 4341.240282950471, 4533.52397998212, 4603.58567728906, 4768.284757286366, 1750.6061136244498, 1907.990460047691, 1945.7956914524054, 1953.4042117154404, 2010.7486564179478, 778.8403377307638, 800.3117319105564, 802.4802121339001, 835.2691395029349, 839.1021606880702, 16585.087820169767, 16765.10609028517, 17318.134093295284, 17411.56471088233, 17588.208530616153, 780.0872978615034, 787.4881327137407, 788.6805843633338, 792.5442870349572, 805.4442434720141, 9012.0, 9416.858747118798, 9815.754606134904, 10274.589016439259, 10280.897191028143, 427144.0, 427265.0, 427611.0, 437198.0, 443686.0, 89984.5500772492, 92458.0, 92958.53520732353, 93231.53384233035, 93325.0, 937.6483818568037, 957.262907561174, 980.2207245240052, 980.5538479991586, 982.8181786369402, 15576.299066541806, 15773.369065237262, 16370.67258654844, 16386.426475073793, 16688.415905443253, 39372.0, 40338.596614033486, 40849.0, 40937.0, 41044.179116416904, 54886.72556637191, 55490.445095550145, 55739.44260557342, 55949.72025139848, 56583.0, 6814.114165158498, 7004.474664400089, 7025.718971241093, 7179.784606461814, 7479.102507699111, 11212.27120237182, 11860.0, 12216.205946613441, 12453.315067671365, 12565.623031309075, 9846.753831154278, 9957.93125448605, 9960.0, 10019.845733167404, 10262.640807571997, 9700.00398930844, 10180.0, 10294.176465882561, 10597.4701264938, 10696.572137114428, 9875.851862221929, 9915.52111972007, 9963.800723985625, 9992.20062394992, 10263.281570290008, 6934.879065389892, 7114.608696521741, 7160.57036577807, 7309.963450182715, 7320.414366850746, 8163.877541836996, 9593.808123837345, 9620.0, 9628.614855405705, 10074.597016119273], "value": 8326.124726520846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1139.8233273842557, 1367.705943222207, 1381.3162484570132, 1384.4462215113954, 1385.099685204617, 6769.390754832064, 7642.808929776758, 7804.141544430108, 7856.567888766115, 8009.239122283374, 7873.330766884822, 7929.6828126875, 7950.0, 7950.24472675095, 7956.641951112199, 2102.84228682849, 2135.957280854382, 2137.4976880433087, 2140.176032227596, 2141.3040761752413, 1767.7436771668101, 1819.0359109671829, 2095.1200495791727, 2147.6241657709916, 2157.2126173946476, 2128.4785227619254, 2137.957240855182, 2141.586552875103, 2141.6466283063355, 2143.2605751015876, 2039.9286024989128, 2067.607154640621, 2081.6773400123006, 2082.0422605601443, 2086.2698055680544, 2121.4378189779723, 2124.7025416441556, 2134.925277615276, 2154.9030293636665, 2156.1590979518, 7807.375409967246, 7930.405219608552, 7950.24472675095, 7952.801179970489, 7971.402144839102, 4572.611328037121, 4716.811327546894, 4718.905621887543, 4719.669623126382, 4726.550977657117, 2760.986195069012, 2827.137722994486, 2966.896158634459, 3018.818870867733, 3042.8630711617807, 1123.7134530694702, 1129.7345123895877, 1157.0974639780984, 1183.0476466444509, 1183.8520184976878, 10581.788364232749, 10634.893651063468, 10647.520861561248, 10658.62694805678, 10659.467026648637, 3905.433712111728, 4123.9175216495505, 4161.126163505698, 4187.183499217624, 4212.220739163265, 9005.369624126248, 9011.098890110936, 9013.369064165445, 9015.233705135137, 9017.77455563603, 4661.627069834405, 4669.089527542097, 4671.813127474863, 4671.8364857229835, 4673.883152921204, 4120.2583534964, 4158.625723684822, 4227.112306415687, 4318.0, 4318.438602981592, 3183.697548732867, 3298.9835050825072, 3322.883699070521, 3346.347462244839, 3350.1959529712694, 1044.6709286574858, 1157.907367410605, 1180.3567055954525, 1181.645506348092, 1197.5329621447643, 778.8948491953627, 794.1026639896879, 797.6171437709932, 798.8562058829466, 821.0926925747083, 5019.799208031638, 5061.620378471629, 5068.619853511002, 5115.283860259567, 5235.764390602432, 861.2507118806553, 862.4868203418956, 863.4819108534829, 872.6204101215991, 900.1943260781695, 3269.623993240808, 3294.1270563300745, 3297.884574039897, 3335.116194208537, 3346.7322614190807, 9189.759382483413, 9395.812083758286, 9410.247180225566, 9414.95292523533, 9470.573824177929, 9310.674126405544, 9354.345195836362, 9446.149846513847, 9452.527373631428, 9464.905350946403, 7300.379467745302, 7327.633618319062, 7359.116905971211, 7366.926330736731, 7370.078740157481, 4614.353990441408, 4680.227762419299, 4708.270218116198, 4742.691725037862, 4752.239641657318, 8356.039055508694, 8357.498550087013, 8489.193526614961, 8570.100139485265, 8588.785280368042, 8318.958405207935, 8639.04970453237, 8730.956345218234, 8738.475691458532, 8751.474911505331, 6378.075179099097, 6559.0817285581015, 6560.212774467094, 6563.0811686362995, 6564.343565643398, 7247.420206383582, 7261.128664560287, 7286.417086633162, 7286.7813965579, 7327.853442931005, 6559.311272316338, 6568.211814582281, 6582.275949645439, 6588.769393071411, 6642.269350371359, 6192.442680158807, 6240.4071613196675, 6245.6252624842655, 6253.28689508722, 6274.435300822948, 5753.280839895013, 5790.073588225863, 5850.034744267318, 5850.736716847667, 5854.356020837789, 4388.605025547716, 4432.401625780479, 4499.167653984024, 4514.32285157229, 4651.906961860676, 4458.799354029012, 4491.708038977456, 4572.794224259983, 4577.793999269974, 4640.512746161605], "value": 4049.7466039007045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.4060972992398, 357.68345014662015, 357.70668052197203, 362.4490774023483, 362.9908853387582, 4931.3835770528685, 4951.504849515047, 4966.106100901836, 4983.676061056029, 5000.324956130926, 4834.951650483494, 4878.975605121969, 4884.0, 4884.682495637781, 4931.211006239003, 3630.673239408453, 3767.8116094195316, 3803.9809800950957, 3834.33206555148, 3850.459816073565, 3790.2798468291076, 3806.90482737931, 3829.047185935179, 3830.712696547756, 3845.538535375759, 3725.068732816796, 3751.8931915085113, 3761.153740408414, 3775.2252094710366, 3775.2826962876966, 3649.251903359805, 3753.774773513598, 3760.480478079693, 3768.246350729851, 3771.5662698789442, 3278.918027049319, 3287.3589650018257, 3296.8516416761304, 3303.8182899940493, 3313.287643156709, 4713.811447542094, 4716.221823399135, 4744.454387745419, 4751.3348131261655, 4765.428148622153, 4255.17024180285, 4280.59334363235, 4281.678874084456, 4283.229018776619, 4296.410328178563, 4837.443693975203, 5022.748862556858, 5078.162103252959, 5085.872853178663, 5104.821331253425, 3609.8375573099274, 3610.772337405295, 3616.1682812952904, 3630.6732394084397, 3640.5085313482605, 5083.542481176675, 5126.38483381989, 5198.714070726109, 5324.9733751331, 5346.117890548055, 3638.890833274953, 3649.5620525537106, 3652.61200743721, 3660.2862441824122, 3661.956342442391, 3722.8696995605565, 3734.9626503734617, 3735.8879233623034, 3736.9439458408146, 3749.0, 4071.42999980003, 4187.72779769314, 4270.402143699885, 4291.570842915746, 4303.677224208197, 4782.115308667908, 5026.597872170218, 5083.847484575469, 5106.106431374569, 5114.462981386975, 1233.6669099343308, 1241.5158088345552, 1243.6766440725476, 1248.850137983448, 1248.9875101248872, 807.7415227127381, 849.0957130655831, 863.1799790199336, 874.156439036333, 875.9343049271203, 4225.746455212636, 4265.936010959838, 4273.529911709709, 4280.700350975402, 4281.764502952367, 5084.3644544431945, 5085.694298705796, 5087.659676548829, 5101.362329708786, 5133.717645529457, 4176.976640723043, 4210.136921930997, 4231.280682284019, 4292.411807631196, 4304.139172165516, 2541.8601976891077, 2698.123109989261, 2706.998410588056, 2707.648005759239, 2716.225875625477, 4767.070421267819, 4781.0, 4798.160321943646, 4808.182608956486, 4808.783604737795, 4909.7790599423115, 5205.609579281569, 5205.6616319939085, 5208.557272631793, 5221.216817477332, 4737.218358970731, 4752.52474752522, 4762.690425122425, 4768.47546769855, 4784.473707892129, 5056.797728090835, 5062.873428164326, 5068.983954547039, 5075.4773567930215, 5084.745762711776, 4695.741734204652, 4988.8752781180765, 5007.624428167829, 5037.370328708911, 5040.36995375578, 4703.200455922433, 4780.709208513612, 4800.3759511263315, 4801.663883528259, 4810.6187437218805, 3896.298666240104, 3939.763614183158, 3975.1453437511072, 4018.4976877890263, 4028.8388464461095, 5253.737313134405, 5268.947310526845, 5272.578193744568, 5278.736063196901, 5284.022455845681, 4853.563179313879, 5078.162103252922, 5106.62121227274, 5119.46245644202, 5137.845864624067, 5077.289179514797, 5090.032893750175, 5094.032133894548, 5103.591712663052, 5105.668131571436, 5038.697678139323, 5043.697378157322, 5049.368828896388, 5071.239314102767, 5073.036123136592, 4901.2893130496595, 5077.543021128116, 5129.615278854176, 5132.486751324837, 5160.406553246425, 4978.103941290602, 5014.724190169576, 5018.623603229846, 5024.246363045572, 5026.371703537058], "value": 3811.1749680961457, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.3669991459093, 596.4542443664047, 694.0734119949867, 703.4091363254867, 705.3968856627583, 11313.15151363648, 12056.216345937508, 12068.094892883026, 12097.697557561065, 12121.212121212126, 11074.778504429907, 11127.332360058383, 11135.0, 11206.159538034639, 12039.939800300986, 807.4590024683447, 828.7099515169702, 834.2741814621288, 838.9748307550752, 841.6212704283066, 829.5810615639094, 842.5492361586553, 842.7724514381114, 843.0094638799417, 843.2158092973532, 885.4996926736405, 886.3485338276362, 887.2857349833381, 887.3034667785789, 887.3477993674644, 880.2122100719884, 880.300161371711, 880.330948479154, 880.9075047120024, 880.9295256379474, 642.4250295985114, 642.6112202117698, 642.935706429358, 643.3309358267396, 643.5977514053717, 13079.607611771662, 13090.14914030585, 13440.932795336059, 13547.59357219285, 13603.183808971395, 12556.309402982832, 13089.672758181026, 13140.802887956688, 13429.342754632047, 13545.796813047813, 10229.751127842039, 10353.430561319125, 10422.062014418663, 10512.947435262851, 10584.788304233873, 3080.8305543195115, 3113.688631136868, 3138.764592655537, 3196.7282780963415, 3228.564143840575, 58282.0, 58588.0, 59474.99999999957, 59736.70131649357, 61350.38649613536, 9418.670346537838, 9566.9043309566, 9657.179139773058, 9964.352317099365, 9972.0, 17065.232064557124, 17078.146092695562, 17103.401380951607, 17122.914385427994, 17170.22733976949, 6268.185135932391, 6341.460975817107, 6541.901871471931, 6543.083968244449, 6561.967190164019, 3478.9304213916084, 3491.965080349215, 3510.57873055235, 3594.6225646307284, 3623.166671665532, 2904.695007024274, 2973.494505933953, 2985.5372417275357, 2990.4467673480476, 2993.4312480629046, 521.9895602087937, 527.9683219006872, 535.1357557544537, 538.9083855744454, 550.3560833824436, 613.2763339259716, 642.6208536963214, 645.6932956845554, 648.6529706606942, 649.909012738217, 7911.683532658629, 8018.438709290293, 8441.944756905386, 8475.618597163142, 9035.683751068682, 613.6134235431649, 630.1933525087895, 634.1597383466925, 637.1239545624766, 642.4057746584391, 3998.4602078719, 4139.606737360006, 4229.513605935295, 4250.362445633118, 4271.295236286041, 61395.38604613897, 62680.37319626745, 63537.0, 63622.0, 65602.67198664072, 52941.47058529365, 53795.731021345426, 54023.45976540263, 54290.72854635781, 55472.0, 43913.78043109764, 46992.7650361746, 47026.76486617545, 49029.50970490249, 49648.25527617161, 8090.959545202236, 8249.092599814136, 8256.339492840438, 8289.502629842229, 8534.573271336532, 28376.85811570929, 29474.0, 34497.13757156091, 35484.29031419409, 35616.82191589129, 40916.59083409128, 41007.58992410038, 41246.58753412547, 41278.79360603178, 42449.93875153146, 11139.944300278446, 11354.205205635526, 11431.59989400367, 11735.41322933867, 11750.414908720068, 12890.548830790878, 12925.935370323088, 13188.208707477583, 13344.266065366499, 13487.595372138852, 11130.0, 11305.321680699184, 11308.273365216963, 11320.660380188607, 12088.456019478968, 12280.140390172959, 12346.876531234573, 12460.813087803508, 13041.304630397988, 13143.408546615616, 11007.83488247693, 11242.100631949588, 11331.263467874562, 11402.20184587104, 11495.0, 9176.6788162414, 9252.95373523155, 9341.813163736551, 9363.344565880323, 9564.282678798978, 9015.864762028707, 9017.639294428413, 9074.682386116454, 9077.909220907706, 9103.681371151977], "value": 6206.993158527429, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 115.0, 116.0, 116.0, 117.0, 115.0, 114.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 125.0, 173.0, 131.0, 132.0, 128.0, 126.0, 147.0, 129.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 166.0, 164.0, 164.0, 162.0, 162.0, 163.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 117.0, 77.0, 78.0, 78.0, 78.0, 94.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 121.0, 85.0, 85.0, 84.0, 106.0, 84.0, 85.0, 114.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 148.0, 149.0, 149.0, 149.0, 148.0, 150.0, 148.0, 150.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 63.0, 64.0, 66.0, 63.0, 62.0, 62.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0, 66.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 87.0, 88.0, 88.0, 89.0, 88.0, 87.0, 88.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 146.0, 137.0, 137.0, 137.0, 144.0, 136.0, 139.0, 175.0, 155.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 188.0, 184.0, 186.0, 186.0, 188.0, 182.0, 183.0, 185.0, 187.0], "value": 186.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 73.0, 72.0, 72.0, 70.0, 71.0, 77.0, 70.0, 71.0], "value": 72.2, "unit": "score"}]}]} 21:14:19 INFO - Return code: 0 21:14:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:14:19 ERROR - # TBPL SUCCESS # 21:14:19 INFO - Running post-action listener: _resource_record_post_action 21:14:19 INFO - [mozharness: 2017-02-21 05:14:19.987132Z] Finished run-tests step (success) 21:14:19 INFO - Running post-run listener: _resource_record_post_run 21:14:20 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 355491840; Read time: 0; Write time: 189636 21:14:20 INFO - TinderboxPrint: CPU usage
13.1% 21:14:20 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:14:20 INFO - TinderboxPrint: I/O write bytes / time
355,491,840 / 189,636 21:14:20 INFO - TinderboxPrint: CPU idle
8,333.0 (86.8%) 21:14:20 INFO - TinderboxPrint: CPU user
1,217.5 (12.7%) 21:14:20 INFO - TinderboxPrint: Swap in / out
0 / 0 21:14:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 104726528; Read time: 0; Write time: 72196 21:14:20 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 250765312; Read time: 0; Write time: 117440 21:14:20 INFO - Running post-run listener: _upload_blobber_files 21:14:20 INFO - Blob upload gear active. 21:14:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:14:20 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 21:14:20 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'] 21:14:20 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 21:14:21 INFO - (blobuploader) - INFO - Open directory for files ... 21:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:21 INFO - (blobuploader) - INFO - Done attempting. 21:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:22 INFO - (blobuploader) - INFO - Done attempting. 21:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:26 INFO - (blobuploader) - INFO - Done attempting. 21:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:14:27 INFO - (blobuploader) - INFO - Done attempting. 21:14:27 INFO - (blobuploader) - INFO - Iteration through files over. 21:14:27 INFO - Return code: 0 21:14:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:14:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:14:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1deefa2c92a4248f644d34ade6878640ddc7edc4f704fbde9f737d3f6dd5ef52335e1edfcd65e30e0cf94e581e6a93feb653e0ee2c67e7c7e1aea301580cb9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1ce1cab99d63aa8fb5a7ce3090cca9d83df6cc845889e70aff63a59a15cf0eb35232417f6e1af266427aaabfa95f37378301a34a10e3212966cfa7321f766d44", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9bcbd9206e6476cbda5ef11552d5188ea766d56deddc719a30b2a3fa93e9f7d719848265300a5311e84aef6b6d06f201b617e7dd049ccf62ab94e8e030accbb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f3470059fe93d1db98e1eb68d0f81bdb76c8c3333bc014467b0cb447c7f8a3ab3c09bda5eb9e38a23e8b432197ad163e3c9bf3b62a8ba8b317ab5bd3442ec8fe"} 21:14:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:14:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:14:27 INFO - Contents: 21:14:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1deefa2c92a4248f644d34ade6878640ddc7edc4f704fbde9f737d3f6dd5ef52335e1edfcd65e30e0cf94e581e6a93feb653e0ee2c67e7c7e1aea301580cb9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1ce1cab99d63aa8fb5a7ce3090cca9d83df6cc845889e70aff63a59a15cf0eb35232417f6e1af266427aaabfa95f37378301a34a10e3212966cfa7321f766d44", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9bcbd9206e6476cbda5ef11552d5188ea766d56deddc719a30b2a3fa93e9f7d719848265300a5311e84aef6b6d06f201b617e7dd049ccf62ab94e8e030accbb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f3470059fe93d1db98e1eb68d0f81bdb76c8c3333bc014467b0cb447c7f8a3ab3c09bda5eb9e38a23e8b432197ad163e3c9bf3b62a8ba8b317ab5bd3442ec8fe"} 21:14:27 INFO - Running post-run listener: copy_logs_to_upload_dir 21:14:27 INFO - Copying logs to upload dir... 21:14:27 INFO - mkdir: /builds/slave/test/build/upload/logs 21:14:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.863427 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 21:14:27.067958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:27.071629) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 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/f1deefa2c92a4248f644d34ade6878640ddc7edc4f704fbde9f737d3f6dd5ef52335e1edfcd65e30e0cf94e581e6a93feb653e0ee2c67e7c7e1aea301580cb9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1ce1cab99d63aa8fb5a7ce3090cca9d83df6cc845889e70aff63a59a15cf0eb35232417f6e1af266427aaabfa95f37378301a34a10e3212966cfa7321f766d44", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9bcbd9206e6476cbda5ef11552d5188ea766d56deddc719a30b2a3fa93e9f7d719848265300a5311e84aef6b6d06f201b617e7dd049ccf62ab94e8e030accbb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f3470059fe93d1db98e1eb68d0f81bdb76c8c3333bc014467b0cb447c7f8a3ab3c09bda5eb9e38a23e8b432197ad163e3c9bf3b62a8ba8b317ab5bd3442ec8fe"} build_url:https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/Bhgef52NTumuZTqjW5EH9g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1deefa2c92a4248f644d34ade6878640ddc7edc4f704fbde9f737d3f6dd5ef52335e1edfcd65e30e0cf94e581e6a93feb653e0ee2c67e7c7e1aea301580cb9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1ce1cab99d63aa8fb5a7ce3090cca9d83df6cc845889e70aff63a59a15cf0eb35232417f6e1af266427aaabfa95f37378301a34a10e3212966cfa7321f766d44", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9bcbd9206e6476cbda5ef11552d5188ea766d56deddc719a30b2a3fa93e9f7d719848265300a5311e84aef6b6d06f201b617e7dd049ccf62ab94e8e030accbb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f3470059fe93d1db98e1eb68d0f81bdb76c8c3333bc014467b0cb447c7f8a3ab3c09bda5eb9e38a23e8b432197ad163e3c9bf3b62a8ba8b317ab5bd3442ec8fe"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:27.096351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:27.096664) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1487651686.590446-789191826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:14:27.228131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:14:27.228459) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:14:27.228790) ========= ========= Total master_lag: 0.22 =========