builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1487694486.56 results: success (0) revision: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.560295) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.560727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.561006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.577135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.577426) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-21 08:28:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-02-21 08:28:06 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.726154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.726921) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020937 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.776596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:06.776931) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa --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 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa --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=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-21 08:28:06,955 truncating revision to first 12 chars 2017-02-21 08:28:06,955 Setting DEBUG logging. 2017-02-21 08:28:06,955 attempt 1/10 2017-02-21 08:28:06,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/74cef34e1927?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 08:28:07,496 unpacking tar archive at: mozilla-central-74cef34e1927/testing/mozharness/ program finished with exit code 0 elapsedTime=0.859123 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:07.648793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:07.649126) ========= script_repo_revision: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:07.649504) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:07.649761) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 08:28:07.660051) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-21 08:28:07.660312) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:28:07 INFO - MultiFileLogger online at 20170221 08:28:07 in /builds/slave/test 08:28:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:28:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:28:07 INFO - {'append_to_log': False, 08:28:07 INFO - 'base_work_dir': '/builds/slave/test', 08:28:07 INFO - 'blob_upload_branch': 'Firefox', 08:28:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:28:07 INFO - 'branch': 'Firefox', 08:28:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:28:07 INFO - 'config_files': ('talos/linux_config.py',), 08:28:07 INFO - 'default_actions': ('clobber', 08:28:07 INFO - 'read-buildbot-config', 08:28:07 INFO - 'download-and-extract', 08:28:07 INFO - 'populate-webroot', 08:28:07 INFO - 'create-virtualenv', 08:28:07 INFO - 'install', 08:28:07 INFO - 'run-tests'), 08:28:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:28:07 INFO - 'download_minidump_stackwalk': True, 08:28:07 INFO - 'download_symbols': 'ondemand', 08:28:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:28:07 INFO - 'tooltool.py': '/tools/tooltool.py', 08:28:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:28:07 INFO - '/tools/misc-python/virtualenv.py')}, 08:28:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:28:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:28:07 INFO - 'gecko_profile': False, 08:28:07 INFO - 'gecko_profile_interval': 0, 08:28:07 INFO - 'installer_path': 'installer.exe', 08:28:07 INFO - 'log_level': 'info', 08:28:07 INFO - 'log_name': 'talos', 08:28:07 INFO - 'log_to_console': True, 08:28:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:28:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:28:07 INFO - 'opt_config_files': (), 08:28:07 INFO - 'pip_index': False, 08:28:07 INFO - 'suite': 'dromaeojs', 08:28:07 INFO - 'system_bits': '32', 08:28:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:28:07 INFO - 'title': 'talos-linux64-ix-057', 08:28:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:28:07 INFO - 'use_talos_json': True, 08:28:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:28:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:28:07 INFO - 'work_dir': 'build'} 08:28:07 INFO - [mozharness: 2017-02-21 16:28:07.944952Z] Running clobber step. 08:28:07 INFO - Running pre-action listener: _resource_record_pre_action 08:28:07 INFO - Running main action method: clobber 08:28:07 INFO - rmtree: /builds/slave/test/build 08:28:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:28:09 INFO - Running post-action listener: _resource_record_post_action 08:28:09 INFO - [mozharness: 2017-02-21 16:28:09.389415Z] Finished clobber step (success) 08:28:09 INFO - [mozharness: 2017-02-21 16:28:09.389516Z] Running read-buildbot-config step. 08:28:09 INFO - Running pre-action listener: _resource_record_pre_action 08:28:09 INFO - Running main action method: read_buildbot_config 08:28:09 INFO - Using buildbot properties: 08:28:09 INFO - { 08:28:09 INFO - "project": "", 08:28:09 INFO - "product": "firefox", 08:28:09 INFO - "who": "ffxbld@noreply.mozilla.org", 08:28:09 INFO - "installer_path": "public/build/target.tar.bz2", 08:28:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:28:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:28:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:28:09 INFO - "stage_platform": "linux64-pgo", 08:28:09 INFO - "basedir": "/builds/slave/test", 08:28:09 INFO - "buildnumber": 41, 08:28:09 INFO - "platform": "ubuntu64_hw", 08:28:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:28:09 INFO - "slavebuilddir": "test", 08:28:09 INFO - "taskId": "VgnrmVhCRrCzHNumC8vFJw", 08:28:09 INFO - "branch": "mozilla-central", 08:28:09 INFO - "script_repo_revision": "production", 08:28:09 INFO - "revision": "74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa", 08:28:09 INFO - "slavename": "talos-linux64-ix-057", 08:28:09 INFO - "repo_path": "mozilla-central" 08:28:09 INFO - } 08:28:09 INFO - Finding installer, test and symbols from parent task. 08:28:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgnrmVhCRrCzHNumC8vFJw'}, attempt #1 08:28:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgnrmVhCRrCzHNumC8vFJw'}, attempt #1 08:28:10 INFO - Task dependencies: UW-aSpgdSu6okAW1GMeBsA 08:28:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA'}, attempt #1 08:28:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 08:28:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/test_packages.json 08:28:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.crashreporter-symbols.zip 08:28:10 INFO - Running post-action listener: _resource_record_post_action 08:28:10 INFO - [mozharness: 2017-02-21 16:28:10.787614Z] Finished read-buildbot-config step (success) 08:28:10 INFO - [mozharness: 2017-02-21 16:28:10.787833Z] Running download-and-extract step. 08:28:10 INFO - Running pre-action listener: _resource_record_pre_action 08:28:10 INFO - Running main action method: download_and_extract 08:28:10 INFO - mkdir: /builds/slave/test/build/tests 08:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:10 INFO - trying https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.test_packages.json 08:28:10 INFO - Downloading https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:28:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:28:11 INFO - Downloaded 896 bytes. 08:28:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:28:11 INFO - Using the following test package requirements: 08:28:11 INFO - {u'common': [u'target.common.tests.zip'], 08:28:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:28:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:28:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:28:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:28:11 INFO - u'mozbase': [u'target.common.tests.zip'], 08:28:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:28:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:28:11 INFO - u'web-platform': [u'target.common.tests.zip', 08:28:11 INFO - u'target.web-platform.tests.zip'], 08:28:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:28:11 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:28:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip 08:28:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:28:11 INFO - Fetch https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip into memory 08:28:12 INFO - Expected file size: 59236078 08:28:12 INFO - Obtained file size: 59236078 08:28:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:28:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip 08:28:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:28:22 INFO - Fetch https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.common.tests.zip into memory 08:28:23 INFO - Expected file size: 59236078 08:28:23 INFO - Obtained file size: 59236078 08:28:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.talos.tests.zip 08:28:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:28:34 INFO - Fetch https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.talos.tests.zip into memory 08:28:35 INFO - Expected file size: 14404319 08:28:35 INFO - Obtained file size: 14404319 08:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:35 INFO - trying https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 08:28:35 INFO - Downloading https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:28:36 INFO - Downloaded 66817291 bytes. 08:28:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 08:28:36 INFO - mkdir: /builds/slave/test/properties 08:28:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:28:36 INFO - Writing to file /builds/slave/test/properties/build_url 08:28:36 INFO - Contents: 08:28:36 INFO - build_url:https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 08:28:36 INFO - Running post-action listener: _resource_record_post_action 08:28:36 INFO - Running post-action listener: set_extra_try_arguments 08:28:36 INFO - [mozharness: 2017-02-21 16:28:36.639872Z] Finished download-and-extract step (success) 08:28:36 INFO - [mozharness: 2017-02-21 16:28:36.639970Z] Running populate-webroot step. 08:28:36 INFO - Running pre-action listener: _resource_record_pre_action 08:28:36 INFO - Running main action method: populate_webroot 08:28:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:28:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:28:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:28:36 INFO - '--tppagecycles', 08:28:36 INFO - '7'], 08:28:36 INFO - 'tests': ['tsvgm']}}, 08:28:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:28:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:28:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:28:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:28:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:28:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:28:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['damp', 'tps']}, 08:28:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'tests': ['damp', 'tps']}, 08:28:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['dromaeo_dom']}, 08:28:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:28:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['basic_compositor_video']}, 08:28:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:28:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['a11yr', 08:28:36 INFO - 'ts_paint', 08:28:36 INFO - 'tpaint', 08:28:36 INFO - 'sessionrestore', 08:28:36 INFO - 'sessionrestore_no_auto_restore', 08:28:36 INFO - 'tabpaint']}, 08:28:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['tsvgx', 08:28:36 INFO - 'tsvgr_opacity', 08:28:36 INFO - 'tart', 08:28:36 INFO - 'tscrollx', 08:28:36 INFO - 'cart', 08:28:36 INFO - 'tsvg_static']}, 08:28:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:28:36 INFO - 'tsvgr_opacity', 08:28:36 INFO - 'tart', 08:28:36 INFO - 'tscrollx', 08:28:36 INFO - 'cart', 08:28:36 INFO - 'tsvg_static']}, 08:28:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'talos_options': ['--disable-e10s'], 08:28:36 INFO - 'tests': ['tp5o']}, 08:28:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:28:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'talos_options': ['--disable-e10s', 08:28:36 INFO - '--xperf_path', 08:28:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:28:36 INFO - 'tests': ['tp5n']}, 08:28:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:28:36 INFO - 'talos_options': ['--xperf_path', 08:28:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:28:36 INFO - 'tests': ['tp5n']}}, 08:28:36 INFO - 'talos.zip': {'path': '', 08:28:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:28:36 INFO - Running post-action listener: _resource_record_post_action 08:28:36 INFO - [mozharness: 2017-02-21 16:28:36.653243Z] Finished populate-webroot step (success) 08:28:36 INFO - [mozharness: 2017-02-21 16:28:36.653338Z] Running create-virtualenv step. 08:28:36 INFO - Running pre-action listener: _resource_record_pre_action 08:28:36 INFO - Running main action method: create_virtualenv 08:28:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:28:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:28:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:28:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:28:36 INFO - Using real prefix '/usr' 08:28:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:28:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:28:39 INFO - Installing pip.................done. 08:28:39 INFO - Return code: 0 08:28:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:28:39 INFO - Reading from file tmpfile_stdout 08:28:39 INFO - Output received: 08:28:39 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) 08:28:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:39 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:28:39 INFO - Using env: {'DISPLAY': ':0', 08:28:39 INFO - 'HOME': '/home/cltbld', 08:28:39 INFO - 'LANG': 'en_US.UTF-8', 08:28:39 INFO - 'LANGUAGE': 'en_US:en', 08:28:39 INFO - 'LOGNAME': 'cltbld', 08:28:39 INFO - 'MAIL': '/var/mail/cltbld', 08:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:39 INFO - 'MOZ_NO_REMOTE': '1', 08:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:39 INFO - 'NO_EM_RESTART': '1', 08:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:39 INFO - 'PWD': '/builds/slave/test', 08:28:39 INFO - 'SHELL': '/bin/bash', 08:28:39 INFO - 'SHLVL': '1', 08:28:39 INFO - 'TERM': 'linux', 08:28:39 INFO - 'TMOUT': '86400', 08:28:39 INFO - 'USER': 'cltbld', 08:28:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:39 INFO - '_': '/tools/buildbot/bin/python'} 08:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:28:39 INFO - Cleaning up... 08:28:39 INFO - Return code: 0 08:28:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:39 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:28:39 INFO - Using env: {'DISPLAY': ':0', 08:28:39 INFO - 'HOME': '/home/cltbld', 08:28:39 INFO - 'LANG': 'en_US.UTF-8', 08:28:39 INFO - 'LANGUAGE': 'en_US:en', 08:28:39 INFO - 'LOGNAME': 'cltbld', 08:28:39 INFO - 'MAIL': '/var/mail/cltbld', 08:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:39 INFO - 'MOZ_NO_REMOTE': '1', 08:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:39 INFO - 'NO_EM_RESTART': '1', 08:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:39 INFO - 'PWD': '/builds/slave/test', 08:28:39 INFO - 'SHELL': '/bin/bash', 08:28:39 INFO - 'SHLVL': '1', 08:28:39 INFO - 'TERM': 'linux', 08:28:39 INFO - 'TMOUT': '86400', 08:28:39 INFO - 'USER': 'cltbld', 08:28:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:39 INFO - '_': '/tools/buildbot/bin/python'} 08:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:39 INFO - Downloading/unpacking psutil>=3.1.1 08:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:28:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:28:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:28:42 INFO - Installing collected packages: psutil 08:28:42 INFO - Running setup.py install for psutil 08:28:42 INFO - building 'psutil._psutil_linux' extension 08:28:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:28:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:28:42 INFO - building 'psutil._psutil_posix' extension 08:28:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:28:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:28:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:28:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:28:42 INFO - Successfully installed psutil 08:28:42 INFO - Cleaning up... 08:28:42 INFO - Return code: 0 08:28:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:42 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:28:42 INFO - Using env: {'DISPLAY': ':0', 08:28:42 INFO - 'HOME': '/home/cltbld', 08:28:42 INFO - 'LANG': 'en_US.UTF-8', 08:28:42 INFO - 'LANGUAGE': 'en_US:en', 08:28:42 INFO - 'LOGNAME': 'cltbld', 08:28:42 INFO - 'MAIL': '/var/mail/cltbld', 08:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:42 INFO - 'MOZ_NO_REMOTE': '1', 08:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:42 INFO - 'NO_EM_RESTART': '1', 08:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:42 INFO - 'PWD': '/builds/slave/test', 08:28:42 INFO - 'SHELL': '/bin/bash', 08:28:42 INFO - 'SHLVL': '1', 08:28:42 INFO - 'TERM': 'linux', 08:28:42 INFO - 'TMOUT': '86400', 08:28:42 INFO - 'USER': 'cltbld', 08:28:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:42 INFO - '_': '/tools/buildbot/bin/python'} 08:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:28:44 INFO - Installing collected packages: mozsystemmonitor 08:28:44 INFO - Running setup.py install for mozsystemmonitor 08:28:45 INFO - Successfully installed mozsystemmonitor 08:28:45 INFO - Cleaning up... 08:28:45 INFO - Return code: 0 08:28:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:45 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:28:45 INFO - Using env: {'DISPLAY': ':0', 08:28:45 INFO - 'HOME': '/home/cltbld', 08:28:45 INFO - 'LANG': 'en_US.UTF-8', 08:28:45 INFO - 'LANGUAGE': 'en_US:en', 08:28:45 INFO - 'LOGNAME': 'cltbld', 08:28:45 INFO - 'MAIL': '/var/mail/cltbld', 08:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:45 INFO - 'MOZ_NO_REMOTE': '1', 08:28:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:45 INFO - 'NO_EM_RESTART': '1', 08:28:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:45 INFO - 'PWD': '/builds/slave/test', 08:28:45 INFO - 'SHELL': '/bin/bash', 08:28:45 INFO - 'SHLVL': '1', 08:28:45 INFO - 'TERM': 'linux', 08:28:45 INFO - 'TMOUT': '86400', 08:28:45 INFO - 'USER': 'cltbld', 08:28:45 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:45 INFO - '_': '/tools/buildbot/bin/python'} 08:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:45 INFO - Downloading/unpacking jsonschema==2.5.1 08:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:28:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:47 INFO - Downloading functools32-3.2.3-2.tar.gz 08:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:28:47 INFO - warning: no files found matching '*.txt' 08:28:47 INFO - no previously-included directories found matching 'build' 08:28:47 INFO - no previously-included directories found matching 'dist' 08:28:47 INFO - no previously-included directories found matching '.git*' 08:28:47 INFO - Installing collected packages: jsonschema, functools32 08:28:47 INFO - Running setup.py install for functools32 08:28:47 INFO - warning: no files found matching '*.txt' 08:28:47 INFO - no previously-included directories found matching 'build' 08:28:47 INFO - no previously-included directories found matching 'dist' 08:28:47 INFO - no previously-included directories found matching '.git*' 08:28:47 INFO - Successfully installed jsonschema functools32 08:28:47 INFO - Cleaning up... 08:28:47 INFO - Return code: 0 08:28:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:48 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:28:48 INFO - Using env: {'DISPLAY': ':0', 08:28:48 INFO - 'HOME': '/home/cltbld', 08:28:48 INFO - 'LANG': 'en_US.UTF-8', 08:28:48 INFO - 'LANGUAGE': 'en_US:en', 08:28:48 INFO - 'LOGNAME': 'cltbld', 08:28:48 INFO - 'MAIL': '/var/mail/cltbld', 08:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:48 INFO - 'MOZ_NO_REMOTE': '1', 08:28:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:48 INFO - 'NO_EM_RESTART': '1', 08:28:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:48 INFO - 'PWD': '/builds/slave/test', 08:28:48 INFO - 'SHELL': '/bin/bash', 08:28:48 INFO - 'SHLVL': '1', 08:28:48 INFO - 'TERM': 'linux', 08:28:48 INFO - 'TMOUT': '86400', 08:28:48 INFO - 'USER': 'cltbld', 08:28:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:48 INFO - '_': '/tools/buildbot/bin/python'} 08:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:28:48 INFO - Cleaning up... 08:28:48 INFO - Return code: 0 08:28:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:48 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:28:48 INFO - Using env: {'DISPLAY': ':0', 08:28:48 INFO - 'HOME': '/home/cltbld', 08:28:48 INFO - 'LANG': 'en_US.UTF-8', 08:28:48 INFO - 'LANGUAGE': 'en_US:en', 08:28:48 INFO - 'LOGNAME': 'cltbld', 08:28:48 INFO - 'MAIL': '/var/mail/cltbld', 08:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:48 INFO - 'MOZ_NO_REMOTE': '1', 08:28:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:48 INFO - 'NO_EM_RESTART': '1', 08:28:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:48 INFO - 'PWD': '/builds/slave/test', 08:28:48 INFO - 'SHELL': '/bin/bash', 08:28:48 INFO - 'SHLVL': '1', 08:28:48 INFO - 'TERM': 'linux', 08:28:48 INFO - 'TMOUT': '86400', 08:28:48 INFO - 'USER': 'cltbld', 08:28:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:48 INFO - '_': '/tools/buildbot/bin/python'} 08:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:49 INFO - Downloading/unpacking blobuploader==1.2.4 08:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:51 INFO - Downloading blobuploader-1.2.4.tar.gz 08:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:28:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:28:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:52 INFO - Downloading docopt-0.6.1.tar.gz 08:28:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:28:52 INFO - Installing collected packages: blobuploader, requests, docopt 08:28:52 INFO - Running setup.py install for blobuploader 08:28:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:28:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:28:52 INFO - Running setup.py install for requests 08:28:52 INFO - Running setup.py install for docopt 08:28:52 INFO - Successfully installed blobuploader requests docopt 08:28:52 INFO - Cleaning up... 08:28:52 INFO - Return code: 0 08:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28: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')]} 08:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28: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')]} 08:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:53 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:53 INFO - Using env: {'DISPLAY': ':0', 08:28:53 INFO - 'HOME': '/home/cltbld', 08:28:53 INFO - 'LANG': 'en_US.UTF-8', 08:28:53 INFO - 'LANGUAGE': 'en_US:en', 08:28:53 INFO - 'LOGNAME': 'cltbld', 08:28:53 INFO - 'MAIL': '/var/mail/cltbld', 08:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:53 INFO - 'MOZ_NO_REMOTE': '1', 08:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:53 INFO - 'NO_EM_RESTART': '1', 08:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:53 INFO - 'PWD': '/builds/slave/test', 08:28:53 INFO - 'SHELL': '/bin/bash', 08:28:53 INFO - 'SHLVL': '1', 08:28:53 INFO - 'TERM': 'linux', 08:28:53 INFO - 'TMOUT': '86400', 08:28:53 INFO - 'USER': 'cltbld', 08:28:53 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:53 INFO - '_': '/tools/buildbot/bin/python'} 08:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:53 INFO - Running setup.py (path:/tmp/pip-VgOS34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:53 INFO - Running setup.py (path:/tmp/pip-zAcOL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:53 INFO - Running setup.py (path:/tmp/pip-S1Dn1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:53 INFO - Running setup.py (path:/tmp/pip-Xpgato-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:53 INFO - Running setup.py (path:/tmp/pip-NZzyGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:53 INFO - Running setup.py (path:/tmp/pip-2_MC5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:53 INFO - Running setup.py (path:/tmp/pip-QxTHvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:53 INFO - Running setup.py (path:/tmp/pip-nlXXd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:53 INFO - Running setup.py (path:/tmp/pip-IBMxlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:53 INFO - Running setup.py (path:/tmp/pip-aYZ2hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:53 INFO - Running setup.py (path:/tmp/pip-Y1bRH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:53 INFO - Running setup.py (path:/tmp/pip-7uzG01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:54 INFO - Running setup.py (path:/tmp/pip-IzIy3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:54 INFO - Running setup.py (path:/tmp/pip-QCuhCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:54 INFO - Running setup.py (path:/tmp/pip-VYRJqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:54 INFO - Running setup.py (path:/tmp/pip-TtQv1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:54 INFO - Running setup.py (path:/tmp/pip-tN0M0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:28:54 INFO - Running setup.py install for manifestparser 08:28:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:28:54 INFO - Running setup.py install for mozcrash 08:28:54 INFO - Running setup.py install for mozdebug 08:28:54 INFO - Running setup.py install for mozdevice 08:28:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:28:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:28:54 INFO - Running setup.py install for mozfile 08:28:54 INFO - Running setup.py install for mozhttpd 08:28:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozinfo 08:28:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozInstall 08:28:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozleak 08:28:55 INFO - Running setup.py install for mozlog 08:28:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for moznetwork 08:28:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozprocess 08:28:55 INFO - Running setup.py install for mozprofile 08:28:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozrunner 08:28:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:28:55 INFO - Running setup.py install for mozscreenshot 08:28:55 INFO - Running setup.py install for moztest 08:28:56 INFO - Running setup.py install for mozversion 08:28:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:28:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:28:56 INFO - Cleaning up... 08:28:56 INFO - Return code: 0 08:28:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:56 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:28:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:56 INFO - Using env: {'DISPLAY': ':0', 08:28:56 INFO - 'HOME': '/home/cltbld', 08:28:56 INFO - 'LANG': 'en_US.UTF-8', 08:28:56 INFO - 'LANGUAGE': 'en_US:en', 08:28:56 INFO - 'LOGNAME': 'cltbld', 08:28:56 INFO - 'MAIL': '/var/mail/cltbld', 08:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:56 INFO - 'MOZ_NO_REMOTE': '1', 08:28:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:56 INFO - 'NO_EM_RESTART': '1', 08:28:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:56 INFO - 'PWD': '/builds/slave/test', 08:28:56 INFO - 'SHELL': '/bin/bash', 08:28:56 INFO - 'SHLVL': '1', 08:28:56 INFO - 'TERM': 'linux', 08:28:56 INFO - 'TMOUT': '86400', 08:28:56 INFO - 'USER': 'cltbld', 08:28:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:56 INFO - '_': '/tools/buildbot/bin/python'} 08:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:56 INFO - Running setup.py (path:/tmp/pip-7dbK5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:56 INFO - Running setup.py (path:/tmp/pip-xoQhru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:56 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)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:56 INFO - Running setup.py (path:/tmp/pip-u9Hkoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:56 INFO - Running setup.py (path:/tmp/pip-8_9Hx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:56 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)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:56 INFO - Running setup.py (path:/tmp/pip-DXJuiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:56 INFO - Running setup.py (path:/tmp/pip-XpYx0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:56 INFO - Running setup.py (path:/tmp/pip-LGfDZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:56 INFO - Running setup.py (path:/tmp/pip-J9oce9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:57 INFO - Running setup.py (path:/tmp/pip-ab6cyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:57 INFO - Running setup.py (path:/tmp/pip-8jgDJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:57 INFO - Running setup.py (path:/tmp/pip-5HwEoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:57 INFO - Running setup.py (path:/tmp/pip-yPbO3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:57 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)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:57 INFO - Running setup.py (path:/tmp/pip-48l7R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:57 INFO - Running setup.py (path:/tmp/pip-d5OUxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:57 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)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:57 INFO - Running setup.py (path:/tmp/pip-PnPRWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:57 INFO - Running setup.py (path:/tmp/pip-1AXXsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:57 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)) 08:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:57 INFO - Running setup.py (path:/tmp/pip-tco3xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:28:57 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)) 08:28:57 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)) 08:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:28:57 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)) 08:28:57 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)) 08:28:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:59 INFO - Downloading blessings-1.6.tar.gz 08:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:28:59 INFO - Installing collected packages: blessings 08:28:59 INFO - Running setup.py install for blessings 08:28:59 INFO - Successfully installed blessings 08:28:59 INFO - Cleaning up... 08:28:59 INFO - Return code: 0 08:28:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:28:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:00 INFO - Reading from file tmpfile_stdout 08:29:00 INFO - Current package versions: 08:29:00 INFO - argparse == 1.2.1 08:29:00 INFO - blessings == 1.6 08:29:00 INFO - blobuploader == 1.2.4 08:29:00 INFO - docopt == 0.6.1 08:29:00 INFO - functools32 == 3.2.3-2 08:29:00 INFO - jsonschema == 2.5.1 08:29:00 INFO - manifestparser == 1.1 08:29:00 INFO - mozInstall == 1.12 08:29:00 INFO - mozcrash == 1.0 08:29:00 INFO - mozdebug == 0.1 08:29:00 INFO - mozdevice == 0.49 08:29:00 INFO - mozfile == 1.2 08:29:00 INFO - mozhttpd == 0.7 08:29:00 INFO - mozinfo == 0.9 08:29:00 INFO - mozleak == 0.1 08:29:00 INFO - mozlog == 3.4 08:29:00 INFO - moznetwork == 0.27 08:29:00 INFO - mozprocess == 0.25 08:29:00 INFO - mozprofile == 0.28 08:29:00 INFO - mozrunner == 6.13 08:29:00 INFO - mozscreenshot == 0.1 08:29:00 INFO - mozsystemmonitor == 0.3 08:29:00 INFO - moztest == 0.8 08:29:00 INFO - mozversion == 1.4 08:29:00 INFO - psutil == 3.1.1 08:29:00 INFO - requests == 1.2.3 08:29:00 INFO - wsgiref == 0.1.2 08:29:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:00 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 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:29:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:29:00 INFO - Using env: {'DISPLAY': ':0', 08:29:00 INFO - 'HOME': '/home/cltbld', 08:29:00 INFO - 'LANG': 'en_US.UTF-8', 08:29:00 INFO - 'LANGUAGE': 'en_US:en', 08:29:00 INFO - 'LOGNAME': 'cltbld', 08:29:00 INFO - 'MAIL': '/var/mail/cltbld', 08:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:00 INFO - 'MOZ_NO_REMOTE': '1', 08:29:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:00 INFO - 'NO_EM_RESTART': '1', 08:29:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:00 INFO - 'PWD': '/builds/slave/test', 08:29:00 INFO - 'SHELL': '/bin/bash', 08:29:00 INFO - 'SHLVL': '1', 08:29:00 INFO - 'TERM': 'linux', 08:29:00 INFO - 'TMOUT': '86400', 08:29:00 INFO - 'USER': 'cltbld', 08:29:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:00 INFO - '_': '/tools/buildbot/bin/python'} 08:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:29:00 INFO - Cleaning up... 08:29:00 INFO - Return code: 0 08:29:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:29: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', 'jsonschema'] in /builds/slave/test/build 08:29: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 jsonschema 08:29:00 INFO - Using env: {'DISPLAY': ':0', 08:29:00 INFO - 'HOME': '/home/cltbld', 08:29:00 INFO - 'LANG': 'en_US.UTF-8', 08:29:00 INFO - 'LANGUAGE': 'en_US:en', 08:29:00 INFO - 'LOGNAME': 'cltbld', 08:29:00 INFO - 'MAIL': '/var/mail/cltbld', 08:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:00 INFO - 'MOZ_NO_REMOTE': '1', 08:29:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:00 INFO - 'NO_EM_RESTART': '1', 08:29:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:00 INFO - 'PWD': '/builds/slave/test', 08:29:00 INFO - 'SHELL': '/bin/bash', 08:29:00 INFO - 'SHLVL': '1', 08:29:00 INFO - 'TERM': 'linux', 08:29:00 INFO - 'TMOUT': '86400', 08:29:00 INFO - 'USER': 'cltbld', 08:29:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:00 INFO - '_': '/tools/buildbot/bin/python'} 08:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:29:00 INFO - Cleaning up... 08:29:00 INFO - Return code: 0 08:29:00 INFO - Running post-action listener: _resource_record_post_action 08:29:00 INFO - Running post-action listener: _start_resource_monitoring 08:29:00 INFO - Starting resource monitoring. 08:29:00 INFO - [mozharness: 2017-02-21 16:29:00.634259Z] Finished create-virtualenv step (success) 08:29:00 INFO - [mozharness: 2017-02-21 16:29:00.634780Z] Running install step. 08:29:00 INFO - Running pre-action listener: _resource_record_pre_action 08:29:00 INFO - Running main action method: install 08:29:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:00 INFO - Reading from file tmpfile_stdout 08:29:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:29:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:29:00 INFO - Reading from file tmpfile_stdout 08:29:00 INFO - Output received: 08:29:00 INFO - Usage: mozinstall [options] installer 08:29:00 INFO - Options: 08:29:00 INFO - -h, --help show this help message and exit 08:29:00 INFO - -d DEST, --destination=DEST 08:29:00 INFO - Directory to install application into. [default: 08:29:00 INFO - "/builds/slave/test"] 08:29:00 INFO - --app=APP Application being installed. [default: firefox] 08:29:00 INFO - mkdir: /builds/slave/test/build/application 08:29:00 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'] 08:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:29:17 INFO - Reading from file tmpfile_stdout 08:29:17 INFO - Output received: 08:29:17 INFO - /builds/slave/test/build/application/firefox/firefox 08:29:17 INFO - Running post-action listener: _resource_record_post_action 08:29:17 INFO - [mozharness: 2017-02-21 16:29:17.577121Z] Finished install step (success) 08:29:17 INFO - [mozharness: 2017-02-21 16:29:17.577395Z] Running run-tests step. 08:29:17 INFO - Running pre-action listener: _resource_record_pre_action 08:29:17 INFO - Running main action method: run_tests 08:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:29:17 INFO - Python 2.7.3 08:29:17 INFO - Return code: 0 08:29:17 INFO - grabbing minidump binary from tooltool 08:29: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')]} 08:29:17 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 0x2207400>, '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 0x22083b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2208840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:29:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:29:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:29:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:29:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:29:17 INFO - Return code: 0 08:29:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:29:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:29:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:29:17 INFO - ENV: RUST_BACKTRACE is now 1 08:29:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:29:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:29:17 INFO - Using env: {'DISPLAY': ':0', 08:29:17 INFO - 'HOME': '/home/cltbld', 08:29:17 INFO - 'LANG': 'en_US.UTF-8', 08:29:17 INFO - 'LANGUAGE': 'en_US:en', 08:29:17 INFO - 'LOGNAME': 'cltbld', 08:29:17 INFO - 'MAIL': '/var/mail/cltbld', 08:29:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:29:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:17 INFO - 'MOZ_NO_REMOTE': '1', 08:29:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:17 INFO - 'NO_EM_RESTART': '1', 08:29:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:17 INFO - 'PWD': '/builds/slave/test', 08:29:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:29:17 INFO - 'RUST_BACKTRACE': '1', 08:29:17 INFO - 'SHELL': '/bin/bash', 08:29:17 INFO - 'SHLVL': '1', 08:29:17 INFO - 'TERM': 'linux', 08:29:17 INFO - 'TMOUT': '86400', 08:29:17 INFO - 'USER': 'cltbld', 08:29:17 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790', 08:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:17 INFO - '_': '/tools/buildbot/bin/python'} 08:29:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:29:17 INFO - mozversion application_buildid: 20170221153143 08:29:17 INFO - mozversion application_changeset: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa 08:29:17 INFO - mozversion application_display_name: Nightly 08:29:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:29:17 INFO - mozversion application_name: Firefox 08:29:17 INFO - mozversion application_remotingname: firefox 08:29:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:29:17 INFO - mozversion application_vendor: Mozilla 08:29:17 INFO - mozversion application_version: 54.0a1 08:29:17 INFO - mozversion platform_buildid: 20170221153143 08:29:17 INFO - mozversion platform_changeset: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa 08:29:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:29:17 INFO - mozversion platform_version: 54.0a1 08:29:17 INFO - using testdate: 1487694557 08:29:17 INFO - actual date: 1487694557 08:29:17 INFO - starting webserver on 'localhost:49397' 08:29:17 INFO - SUITE-START | Running 2 tests 08:29:17 INFO - TEST-START | dromaeo_css 08:29:17 INFO - operating with platform_type : linux_ 08:29:17 INFO - Initialising browser for dromaeo_css test... 08:29:17 INFO - TEST-INFO | started process 15470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6vugIu/profile http://localhost:49397/getInfo.html) 08:29:21 INFO - PROCESS | 15470 | Iterating over profile /tmp/tmp6vugIu/profile 08:29:21 INFO - PROCESS | 15470 | __metrics Screen width/height:1600/1200 08:29:21 INFO - PROCESS | 15470 | colorDepth:24 08:29:21 INFO - PROCESS | 15470 | Browser inner width/height: 1024/697 08:29:21 INFO - PROCESS | 15470 | __metrics 08:29:21 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:21 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/localstore.rdf = Tue Feb 21 2017 08:28:35 GMT-0800 (PST) 08:29:21 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:21 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/safebrowsing = Tue Feb 21 2017 08:29:21 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/extensions.ini = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/cache2 = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/permissions.sqlite = Tue Feb 21 2017 08:29:19 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/sessionCheckpoints.json = Tue Feb 21 2017 08:29:21 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/directoryLinks.json = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/content-prefs.sqlite = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/.parentlock = Tue Feb 21 2017 08:29:17 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/places.sqlite-wal = Tue Feb 21 2017 08:29:22 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/secmod.db = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/blocklist.xml = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/extensions.json = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/bookmarkbackups = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/cert8.db = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/extensions = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/crashes = Tue Feb 21 2017 08:29:17 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/startupCache = Tue Feb 21 2017 08:29:17 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/places.sqlite = Tue Feb 21 2017 08:29:21 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/compatibility.ini = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/user.js = Tue Feb 21 2017 08:29:17 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/key3.db = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/frequencyCap.json = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/places.sqlite-shm = Tue Feb 21 2017 08:29:19 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/pluginreg.dat = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/gmp = Tue Feb 21 2017 08:29:18 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/minidumps = Tue Feb 21 2017 08:29:17 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | Stat failure 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/thumbnails = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/addons.json = Tue Feb 21 2017 08:29:20 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | No birth date. Using mtime. 08:29:22 INFO - PROCESS | 15470 | Using date: /tmp/tmp6vugIu/profile/prefs.js = Tue Feb 21 2017 08:28:35 GMT-0800 (PST) 08:29:22 INFO - PROCESS | 15470 | 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" 08:29:22 INFO - PROCESS | 15470 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:29:24 INFO - TEST-INFO | 15470: exit 0 08:29:24 INFO - Browser initialized. 08:29:24 INFO - Running cycle 1/1 for dromaeo_css test... 08:29:24 INFO - TEST-INFO | started process 15564 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6vugIu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:35 INFO - PROCESS | 15564 | RSS: Main: 143196160 08:29:35 INFO - PROCESS | 15564 | 08:32:37 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49397/tests/dromaeo/cssquery-ext.html) 08:32:39 INFO - PROCESS | 15564 | RSS: Main: 305274880 08:32:39 INFO - PROCESS | 15564 | 08:34:25 INFO - PROCESS | 15564 | ************************* 08:34:25 INFO - PROCESS | 15564 | A coding exception was thrown and uncaught in a Task. 08:34:25 INFO - PROCESS | 15564 | 08:34:25 ERROR - PROCESS | 15564 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:34:25 INFO - PROCESS | 15564 | Full stack: 08:34:25 INFO - PROCESS | 15564 | ************************* 08:35:41 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-ext.html (next: http://localhost:49397/tests/dromaeo/cssquery-jquery.html) 08:35:42 INFO - PROCESS | 15564 | RSS: Main: 237264896 08:35:42 INFO - PROCESS | 15564 | 08:38:44 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49397/tests/dromaeo/cssquery-mootools.html) 08:38:45 INFO - PROCESS | 15564 | RSS: Main: 209936384 08:38:45 INFO - PROCESS | 15564 | 08:41:47 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49397/tests/dromaeo/cssquery-prototype.html) 08:41:47 INFO - PROCESS | 15564 | RSS: Main: 183177216 08:41:47 INFO - PROCESS | 15564 | 08:44:50 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49397/tests/dromaeo/cssquery-yui.html) 08:44:51 INFO - PROCESS | 15564 | RSS: Main: 182263808 08:44:51 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-yui.html (next: http://localhost:49397/tests/dromaeo/cssquery-dojo.html) 08:47:54 INFO - PROCESS | 15564 | RSS: Main: 277893120 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | __start_tp_report 08:47:54 INFO - PROCESS | 15564 | _x_x_mozilla_page_load 08:47:54 INFO - PROCESS | 15564 | _x_x_mozilla_page_load_details 08:47:54 INFO - PROCESS | 15564 | |i|pagename|runs| 08:47:54 INFO - PROCESS | 15564 | |0;dojo.html;3145.795523290987;3159.9368012639748;3184.4586420308547;3193.3134376109147;3211.534327522511;5228.451012643674;5320;5325.813596524123;5365.624406291561;5383.16560933055;5347.278117454142;5359.732013399324;5459.071957767179;5460.5085542301185;5499.697516636584;4889.779959901813;4920.089783390066;4938.962817808256;5035.3454050973505;5041.3194218780545;5010.423801262847;5024.4724303948105;5094.923576146342;5100.209467532539;5129.871753206163;4790.401199850019;4817.975910120427;4820.493848145929;4896.192128298826;5023.422306434752;4883.804647814083;4891.657583969123;4896.3389942358035;4914.238293064581;4966.776495057731;4939.333190019337;4945.109880221558;4982.60139188864;4990.725510096943;5022.89954200914;5412.431694672082;5418.431064738224;5439.075357189287;5447.346318441772;5512.3385193777;5545.085060964976;5579.804706835242;5796.333421656479;5799.681017544033;5800.680962547058;5617.0731829248125;5637.408072152447;5664.575156863251;5675.602707810455;5683.829485115453;5288.788448462095;5298.364196296469;5300.628955973083;5302.337207849019;5304.257403963448;5724.713764311768;5780.930527852361;5782.624129431573;5791.0734282514595;5791.305043394736;5538.030844602178;5550.361708403505;5671.064274394678;5721.199032135504;5729.7708091676695;5775.768969241183;5785.82642520725;5793.131030345482;5800.361960184377;5836.358000619928;5753.88492230153;5763.337216220189;5764.625299355528;5769.451902069297;5770.682612456355;5569.359523654751;5598.552115830724;5606.6636001840025;5658.547316214693;5762.510186634183;4399.64802815774;4442.666799990014;4452.9554704453185;4469.150861336336;4510.684252102387;3997.620226078518;3999.92000160001;4006.358982562776;4024.1549274652066;4040.919181616351;5511.3937466878615;5520.558355331564;5521;5522.834314970557;5567.025770490148;5591.30108736408;5636;5642.210090587321;5701.543876489871;5866.1494083357675;5383.973080134652;5431.131019036935;5452.563794896399;5490.615656904059;5554;4966.056449274626;4966.503349665005;4966.6771659842;4982.153033984231;5004.074246264453;6037.1246169305205;6041.063635136561;6079.75680972765;6092.482139018233;6099.237595300588;5694.345150307602;5754.367019627922;5757.5681823864215;5798.10129429939;5799.884002319845;6029;6050.576459647782;6068.908966365596;6099.340048627855;6109.040306147876;5730.512906402836;5731.770729170953;5751.626144300607;5753.626014309057;5787.7395517200985;5612.550995920398;5812.157237200667;5860.824175274749;5867.237259156283;5869.442402971711;5535.806246781504;5594.608377413705;5599.440055994531;5621.775128994795;5640.125780504029;5679.176519404746;5679.176519404746;5681.602287839977;5712.742926568398;5733.168690539932;5667.574931880043;5669.716514174357;5669.744861481161;5670.347909990239;5674.092145256739;5563.916541251803;5614.326280846324;5617.859553511195;5659.518940890071;5697.601167918206;5472.835814925558;5494.2033405155225;5511.834644960658;5517.61376703643;5522.282103326542 08:47:54 INFO - PROCESS | 15564 | |1;ext.html;537.2221659601761;597.4533302028644;600.9008513595257;602.1209034809178;602.4246844263727;29896.850515747417;30403.69596304036;42780.35829462557;43938;44065;42343.36484952728;45750.54249457504;45758.31362529565;45761;45908.77045614767;8993.999999999984;9170.312226582999;9194.26445884331;9197.862032069525;9226.169644732006;7352.779416617484;7515;8935.955320223422;10559.26085174038;10786.406747628876;10035.147012503947;10090.747731306703;10090.74773130674;10126.341787783807;10153.187744980421;10142.340747851404;10188.898111018943;10195.286329956905;10195.337303075275;10208.744781380525;10609.575616975311;10636.893631063667;10642.201834862417;10668.679939601825;10689.358638481715;42257.57742422598;42404.57595424037;42503.36244956328;42654;42658.57341426577;3716.2939041582285;3816.1985982943615;3828.4257361395735;3874.728768986172;3877.495925529663;30862.22844428885;30896.84551577228;30903;31344.276064816426;31459;3950.6839452843706;4063.878083657495;4083;4092.283850326181;4221.852235171785;301816;302165;302634;302738;303991;56588.43411565832;56766;56896.715516422155;57046.42953570494;57100.42899571034;60799.392006080256;60993.695031524556;61091.38908610946;61109.69445152835;61163;13992.30038498071;14191.858081419054;14191.858081419261;14201.644958875904;14219.786703199461;9448.716538503855;10742.409167495864;10783.083437907866;10785.622503212508;10834.458277086114;13671.521496747566;17431.63779941137;21006.78993210079;21351.67972480414;21761.782382176294;7412.4440666950195;7462.888056679154;7582.469227154046;7617.542947423172;8066.435349525595;854.5812551849673;884.7832281091174;893.6112790935833;899.321012635456;928.2852203803169;30194.54708179379;30606.54090188649;30705.69294307073;30710.38579228403;31161.351285140565;1036.0934182590233;1037.8339465685606;1044.556063672936;1062.2245760594808;1178.5874943769784;15016.474423395348;15809.209539522977;16069.51791446235;16172.75740863888;16540.83459165417;467597;467895;468621;470665;471904;60631.39368606346;60841.69579152076;61098.69450652718;61157.69421152866;61210.69394652998;1316.1905428161804;1353.093427403642;1357.2942070123481;1379.7585422551012;1397.2524699285834;28240.24552388766;28827.56758648664;29482.410351793274;29897.850510747307;30278.09165725032;50034.49965500298;51080.23379649383;51225.23162152645;51389.48610513847;51433;404052;404426;405962;406086;406711;23939.76060239375;24494;27411.862940685965;27948.58077128804;27954.16137515878;42801.35797963096;47757;48253;48309.067637294116;48629;38336.2332753349;38851.80574097111;39072;39272.60727392689;40652.292371758325;38302.063180207115;38539.229215414976;39613;39933;40077.398839018024;38474;38575.6142438572;38783.22433551257;38937.6106238934;39651.2069758609;31788.364232714754;32727.18182045468;33667.15832104217;33836.66163338335;34383.4842477358;26154.607680884423;26517.60223596686;26689.33276668099;26722.59916101299;26819.463610727285 08:47:54 INFO - PROCESS | 15564 | |2;jquery.html;417.99373009404866;418.7633986797457;424.65815018909785;435.36436802268685;450.5517010574479;19598;19662.213511459544;19955.600887982233;20046.49651276153;20121.899390503044;18558.35045773398;19458.610827783436;20916;21029.474263143427;21190.152393904224;5705.914411283824;5939.2575928009;5964.701764911759;5977.252843394575;5995.250593675791;5685.800996965107;5827.970860145693;5868.2664666916635;5919.230500035011;5965.045592705168;6039.667818269993;6042.667653279068;6050.092486127072;6050.6369617823075;6051.2133422655;5960.254968128984;5985.6408615483;6041.335453100155;6052.848678783022;6058.697065146725;5815.82552523425;5900.498457631106;5923.644581325134;5933.8813223735715;5935.198748168985;14481.78277325841;14712.264386780726;14828.036177648419;14883.47907823221;14884.702305953928;2524.6591710119264;2564.217913536371;2628.487444948236;2634.7233540478164;2663.240976321739;10072.848907266396;10306.845397319046;10992.230543861933;12727.490900363975;14398.784018239734;1884.6513395021964;1888.8772229805018;1902.0965041605127;1923.8941858197793;1936.2545420013396;80852.59573702153;86129.56935215347;87567.56216218878;88864;91139;11609.593664221711;11739.882601173878;11872.881271187178;12131.696707582381;12424.56514022005;17449.389271375694;17468.912655436896;17492.300307987793;17500.299988000592;17506.12469376526;7556.65995030225;7605.429592780453;7673.693052277958;7702.037245344332;7762.728304509315;6863.450923926074;6989.405900498515;6991.580505169604;7053;7091.680874360666;5029.622778291643;5029.84910452687;5158.3552055993005;5391.32608423947;5404.972975135099;2087.4363921741347;2144.946376340573;2184.650455927044;2196.1325276515686;2228.598852206586;837.2590257622048;839.4249938791947;840.1892174052069;861.401326078382;878.4246318661287;18782.90608546976;18863.811361886204;19456.805431945784;20162.69755953662;20220.39338819838;843.679401827314;846.9153084691482;848.4357901995228;850.5279569838799;863.2921004776024;10623.309484883457;11138.108951283877;11237.381943992996;11244.66266012021;11246.100311975022;456662;460540;465533;602497;622397;84357.57821210855;91596;93865;95173;101611.49194253981;1090.0679918669423;1127.672974837288;1134.444122380028;1135.3301552084145;1140.9201355905006;10767.461626918466;10788.460576970963;11249.943750281196;11742;11852.348120853436;39951.2009759809;40686.593134069466;44345.33481997837;45877.08245834998;46047.309290360005;64804.675976619816;64879.40482380841;65151.34848651453;65244;66384.0847745684;12687.619371418872;13090.672733181747;13174.209547427185;13265.66835829112;13446.46214151423;9057.501837399006;11342.97913187817;14328;14568.635784105481;14578.781318280446;12459.813102803284;12526.624201273977;12565.87434125647;12611.558595449114;12786.424610892718;12412.069094817743;12472.438740256528;12560.43478043472;12585.231249125178;13035.739285214433;6875.312468753084;10605.41670208146;12159.75680486403;12633.778386511382;12948.8057679133;12695.238285702888;12749.808752868528;12838.229706217657;12976.740465190833;13030.153040052366;12135.271883687006;12197.878021219674;12299.815502767644;12310.630681079581;12790.296533690736 08:47:54 INFO - PROCESS | 15564 | |3;mootools.html;1421.1686163594297;1438.2449214162564;1456.6868123353483;1460.5618314505646;1473.5358362115933;8284.544350060743;8730.825383492327;8732.607032683529;8735.519546424952;8753.781155471117;8440.240378365947;8622.784430389243;8664.610092545834;8667.479951202933;8873.778155546115;2284.7715228477186;2335.649652552114;2336.1589827662115;2342.203650758742;2342.320726989171;2275.943101422461;2330.0912644068826;2334.5681049005902;2338.1348900906605;2339.3683705399617;2244.62963611004;2270.409693479699;2288.1762565476415;2296.5636529059425;2299.5975704251773;2249.3139592424304;2256.131389415079;2265.762094980028;2272.5000499889998;2277.5900337939165;2215.2468160825397;2226.053927080984;2238.832087593437;2239.0260236797044;2249.392663980716;8839.02770695223;8908.287337013024;8951.283897288202;8954.417962832395;8956.462612243286;5134.820281290137;5259.211118332242;5264.420913699529;5264.763085661154;5268.947310526923;3118.750499959998;3167.4298626247264;3263.8368081595827;3330.81680507572;3354.111160542458;1393.3799459240643;1402.3689339797131;1427.1936355958953;1434.0535246737209;1468.8971771975966;10723.785524289471;10995.835062474069;11536.76926461466;12124.39378031095;12264.325462099581;4405.66957478184;4588.105319462739;4645;4673.859784206479;4678.976605117021;10583.57665693379;10587.470626468647;10587.523561439753;10596.682099537025;10606.204534659932;4586.954130458719;4740.786664600101;5305.893882122413;5315.123004704181;5335.599830012765;4667.183242932541;4806.543378379049;4856.125897338442;4857.611391088704;4858.58702010326;3769.717271204616;3878.8448462061765;3930.5283365996265;3956.5252169739815;3998.3602623580086;1209.8124790657462;1235.975280494385;1243.9378031098408;1250.056207563302;1254.4355040231933;760.9124950630637;773.9187385324573;775.7595145504912;788.6569342336102;792.3304807437706;5191.740412979336;5247.973760131251;5263.157894736823;5297.258383826267;5346;587.9882402351928;588.3616276340133;600.798732424634;603.4448307557058;606.0333767640694;3463.7229021678204;3468.3410152071024;3541.592716837545;3543.3267679141395;3605.2068544920076;10338.534765935401;10652.62715804943;10653.893461065445;10665.573377064831;10694.946525267324;9944.30389872702;10185.388876667424;10533.052025317758;10588.364698118137;10603.098736607475;8001;8192.057913340042;8210.096889342287;8210.58947052657;8228.62971166299;5204.2714020036465;5327.920081198863;5375.113106337567;5427.592930530305;5613.915791263053;9019.594118264562;9282.164605185566;9543.856842147512;9641.903580964381;9653.46905920153;9292.442453452815;9430.528473576156;9669.226461883172;9717.757056073655;9860.704178874645;7345.265473452612;7403.629818509161;7433.479656423999;7455.142658594114;7507.587082710504;8040.356771458152;8088.676452941816;8117.269445749911;8127.75616731499;8152.796180095545;7128.750493732695;7230.276972302728;7232.132144142736;7245.5652660840515;7429.777106686808;7112.217656057933;7144.214136444883;7160.10498687664;7197.172325182671;7258.274172582699;6094.939050609614;6159.630422174684;6193;6197.504199663926;6204.534659900435;4714.528547145258;4761.880952976065;4763.261694437368;4828.5412885775795;4883.853484395474;4647.535246475325;4654.185517534485;4702.247640377524;4782.904341913073;4918.852434426973 08:47:54 INFO - PROCESS | 15564 | |4;prototype.html;375.64876840154443;400.27350359098244;404.31065034116835;405.84780707234785;408.31607058177565;5256.894862102756;5282.181261904401;5335.119705248634;5384.6499977501435;5389.676619402833;5035.622328325381;5155.7679904404295;5158.664686795365;5167.6641018333785;5196.818111366103;4110.938335924956;4144.399062135988;4152.73007254529;4164.333706606943;4175.31107367284;4134.173165366924;4146.709730318879;4156.709030367875;4157.792110394468;4162.708610397273;3911.8630847920326;4108.691848111389;4118.649914757234;4138.834446622147;4143.337066069429;4119.423280740669;4130.876073717778;4135.560019588427;4136.400221967828;4136.772477513736;3662.4506324051604;3665.7617254878346;3698.3527882620433;3699.741018128705;3708.9443658345144;4870.7564621769125;5114.335136432277;5162.683515703471;5184.196449550288;5218.617066477386;4704.223803072489;4707.7881495332795;4708.317293992386;4712.481627021025;4713.528647135293;5539.19681646159;5545.972270138664;5557.221988921554;5558.888222355545;5575.275214222166;3801.3347664158937;3980.383040628707;4003.959960400388;4014.41790940312;4041.2523683118716;5438.619296649236;5544;5547.55619550439;5661.34894487131;5714.457126572969;4075.9388609170887;4085.142120154801;4103.610157035077;4106.281400754916;4128.174365126986;4092.4066010428323;4125.257453658371;4151.916961660811;4157.230912281238;4180.498340199196;4388.539203383662;4497.865064048084;4577.7482238477205;4594.862154135314;4666.953330466652;5497.697626630574;5505.752241149078;5515.393306736256;5519.868426972541;5541.556675465953;1468.7723402872573;1472.7201831651953;1489.0395694776807;1509.679030848008;1512.289224064688;1027.4092396871679;1078.1751959750798;1080.7841178673991;1085.934843909368;1101.3942331717524;5114.8235905741885;5150.613703972217;5156.61325400589;5176.4047134580005;5189.896202075937;5473.233747275384;5514.448555144453;5540.972295138498;5583.218349431083;5597.244372009808;4986.526280003466;5134.204198349262;5145.9485405146215;5152.742362881841;5152.819651312186;2886.134159752066;2899.6230490036583;2903.245156259388;2905.898293559715;2906.5785461108444;5520.095567029369;5646.463585959327;5693.772249110072;5699.499982516808;5704.515116215168;5534.695591742493;5543.223948647193;5547.41752116022;5562.86092847674;5563.916541251884;5674.574406919498;5676.984670442016;5678.744456499385;5682.687452190169;5707.229524014205;5199.246109314053;5364.114921038141;5366.275552800322;5369.731513424391;5457.263269458573;5366.302380690641;5388.784448622011;5388.841399099212;5390.434004429479;5418.539424148991;5416.4041955385665;5601.815292036191;5690.154922538731;5694.914576281442;5716.742746576331;4279.99420136274;4290.978545107255;4317.8272869084885;4329.467261651969;4343.804528796149;5649.293838270216;5685.34618518859;5698.230738850202;5702.486776190163;5724.255846739898;5261.736913154404;5528.557715382679;5534.520824383751;5572.331320241597;5592.888142237214;5509.201165831012;5521.779128834802;5532.751026203911;5533.474319939599;5571.582131340247;5327.573794096541;5545.417731138173;5546.805861794818;5553.8333849984565;5569.55443564522;5538.6399884007415;5553.278073850373;5561.192612400905;5564.443555644403;5576.0241957656435;5435.83692489226;5443.020256353736;5448.83653490396;5493.5442107842255;5496.890062198654 08:47:54 INFO - PROCESS | 15564 | |5;yui.html;717.989230161548;744.5867543513351;765.7319938021693;787.9881801772973;808.3129340060947;14552.92723536382;15736.213189340517;15745.60635984101;15857.920710396433;15900.602484937868;14457.204853733041;14656.633584160401;14680.779788303184;14681.853181468183;14687.63280917975;760.8668483015464;762.7635433015755;782.9491083079581;783.1933108897816;794.3486341200218;770.6185438208067;770.8728059870115;771.1632878327022;771.240328276647;771.5679219636993;827.3753316246226;829.3945419843518;829.5396055189367;829.618375547249;829.6764261937849;821.1172989036779;821.6549049399237;821.9054808697017;822.2065706593166;823.2097186700775;604.2537466229203;604.8760004199129;605.1769593353036;605.6366180291834;605.9848503787397;14995;15381.615459613487;15583.532494025198;15720.764188537181;15745;14294;14542.854571454256;14563.271836408137;14734.926325368304;14806.925965370105;11406;11475.713107172303;11591.6522504325;11675.591354302644;11687.065034797195;3527.982360088183;3537.1157210697324;3574.410222313315;3613.4579813027995;3736.23407201523;69242;73234.63382683106;73911.26088739098;74305;75029.62485187594;10574.788504229873;10770.353778773298;10771.78456430867;10835.079018283377;10877.891221087688;21570;21573.352799416043;21578.892105539373;21579.028943697256;21628.45928851791;7096.290370962863;7529.171791102975;7655.732049378245;7666.041744781903;7690.923090769021;4445.666575006888;4448.243798554254;4492.775361231926;4528.954710452919;4614.861554153381;3421.4354631486026;3537.6108628050893;3540.256546125291;3541.3979623464074;3549.893503194908;599.3586862057643;604.4348534120611;607.7994261893565;619.1301221783401;632.2192092765431;600.3006497430445;616.9259688837368;619.4827319188438;619.8574327904607;623.8783437229697;8618.353623478264;8762.518061506678;8851.867221991675;9141.436157565184;9397.248220142372;651.7849109793757;663.4891133826926;670.168990451846;671.4997326991422;681.9556728812611;4084.8570300039355;4337.284348082531;4444.711093778894;4465.754383508874;4530.141492608341;73914.6304268486;76438.23561764423;76551.61724191454;77048.22951770523;77644;64467.67766161139;65397;65859.67070164619;67999.66000169968;68904.6554767223;59813.541463804475;60126.784785825104;60843.99999999911;62072.68963655153;62086.37913620896;8466.619002144795;8689.261412779731;8772.473651580925;8800.339974502065;8956;42454.363184551636;42685.719428415956;42958.78520607502;43195.78402107969;45475;49506.25740613822;51860.74069629628;52033.47966520286;52630.47369526256;54189.729051354494;10221.23340749432;10272.178225742073;10361.326513776881;10370.740731481774;10409.1672666185;10975.890241097486;11023.441739139105;11268.436578171222;11526.366049867338;11557.929137937745;10549.947250264007;10827.783444330911;11006.559737610727;11123.944380278046;11383.829242561535;10995.615153469591;11216.270942389045;11467.655970320904;11564.826527601925;11851.229670071418;9846.885684554893;10242.334248273839;10390.22073344487;10425.426601536989;10740.946295268473;8507.872381914152;8702.782430439036;8760.693375731818;8912.688055918012;9011.774705632411;8093.959530202312;8527.360447966552;9036.412633178823;9238;9279.350445468752 08:47:54 INFO - PROCESS | 15564 | __end_tp_report 08:47:54 INFO - PROCESS | 15564 | __start_cc_report 08:47:54 INFO - PROCESS | 15564 | _x_x_mozilla_cycle_collect,1579 08:47:54 INFO - PROCESS | 15564 | __end_cc_report 08:47:54 INFO - PROCESS | 15564 | __startTimestamp1487695674445__endTimestamp 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | ------- Summary: start ------- 08:47:54 INFO - PROCESS | 15564 | Number of tests: 6 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#0] dojo.html Cycles:165 Average:5367.50 Median:5554.00 stddev:588.99 (10.6%) stddev-sans-first:564.42 08:47:54 INFO - PROCESS | 15564 | Values: 3145.8 3159.9 3184.5 3193.3 3211.5 5228.5 5320.0 5325.8 5365.6 5383.2 5347.3 5359.7 5459.1 5460.5 5499.7 4889.8 4920.1 4939.0 5035.3 5041.3 5010.4 5024.5 5094.9 5100.2 5129.9 4790.4 4818.0 4820.5 4896.2 5023.4 4883.8 4891.7 4896.3 4914.2 4966.8 4939.3 4945.1 4982.6 4990.7 5022.9 5412.4 5418.4 5439.1 5447.3 5512.3 5545.1 5579.8 5796.3 5799.7 5800.7 5617.1 5637.4 5664.6 5675.6 5683.8 5288.8 5298.4 5300.6 5302.3 5304.3 5724.7 5780.9 5782.6 5791.1 5791.3 5538.0 5550.4 5671.1 5721.2 5729.8 5775.8 5785.8 5793.1 5800.4 5836.4 5753.9 5763.3 5764.6 5769.5 5770.7 5569.4 5598.6 5606.7 5658.5 5762.5 4399.6 4442.7 4453.0 4469.2 4510.7 3997.6 3999.9 4006.4 4024.2 4040.9 5511.4 5520.6 5521.0 5522.8 5567.0 5591.3 5636.0 5642.2 5701.5 5866.1 5384.0 5431.1 5452.6 5490.6 5554.0 4966.1 4966.5 4966.7 4982.2 5004.1 6037.1 6041.1 6079.8 6092.5 6099.2 5694.3 5754.4 5757.6 5798.1 5799.9 6029.0 6050.6 6068.9 6099.3 6109.0 5730.5 5731.8 5751.6 5753.6 5787.7 5612.6 5812.2 5860.8 5867.2 5869.4 5535.8 5594.6 5599.4 5621.8 5640.1 5679.2 5679.2 5681.6 5712.7 5733.2 5667.6 5669.7 5669.7 5670.3 5674.1 5563.9 5614.3 5617.9 5659.5 5697.6 5472.8 5494.2 5511.8 5517.6 5522.3 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#1] ext.html Cycles:180 Average:55647.28 Median:21225.17 stddev:105313.31 (496.2%) stddev-sans-first:105527.46 08:47:54 INFO - PROCESS | 15564 | Values: 537.2 597.5 600.9 602.1 602.4 29896.9 30403.7 42780.4 43938.0 44065.0 42343.4 45750.5 45758.3 45761.0 45908.8 8994.0 9170.3 9194.3 9197.9 9226.2 7352.8 7515.0 8936.0 10559.3 10786.4 10035.1 10090.7 10090.7 10126.3 10153.2 10142.3 10188.9 10195.3 10195.3 10208.7 10609.6 10636.9 10642.2 10668.7 10689.4 42257.6 42404.6 42503.4 42654.0 42658.6 3716.3 3816.2 3828.4 3874.7 3877.5 30862.2 30896.8 30903.0 31344.3 31459.0 3950.7 4063.9 4083.0 4092.3 4221.9 301816.0 302165.0 302634.0 302738.0 303991.0 56588.4 56766.0 56896.7 57046.4 57100.4 60799.4 60993.7 61091.4 61109.7 61163.0 13992.3 14191.9 14191.9 14201.6 14219.8 9448.7 10742.4 10783.1 10785.6 10834.5 13671.5 17431.6 21006.8 21351.7 21761.8 7412.4 7462.9 7582.5 7617.5 8066.4 854.6 884.8 893.6 899.3 928.3 30194.5 30606.5 30705.7 30710.4 31161.4 1036.1 1037.8 1044.6 1062.2 1178.6 15016.5 15809.2 16069.5 16172.8 16540.8 467597.0 467895.0 468621.0 470665.0 471904.0 60631.4 60841.7 61098.7 61157.7 61210.7 1316.2 1353.1 1357.3 1379.8 1397.3 28240.2 28827.6 29482.4 29897.9 30278.1 50034.5 51080.2 51225.2 51389.5 51433.0 404052.0 404426.0 405962.0 406086.0 406711.0 23939.8 24494.0 27411.9 27948.6 27954.2 42801.4 47757.0 48253.0 48309.1 48629.0 38336.2 38851.8 39072.0 39272.6 40652.3 38302.1 38539.2 39613.0 39933.0 40077.4 38474.0 38575.6 38783.2 38937.6 39651.2 31788.4 32727.2 33667.2 33836.7 34383.5 26154.6 26517.6 26689.3 26722.6 26819.5 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#2] jquery.html Cycles:180 Average:30422.39 Median:2164.80 stddev:86902.30 (4014.3%) stddev-sans-first:87116.88 08:47:54 INFO - PROCESS | 15564 | Values: 418.0 418.8 424.7 435.4 450.6 19598.0 19662.2 19955.6 20046.5 20121.9 18558.4 19458.6 20916.0 21029.5 21190.2 5705.9 5939.3 5964.7 5977.3 5995.3 5685.8 5828.0 5868.3 5919.2 5965.0 6039.7 6042.7 6050.1 6050.6 6051.2 5960.3 5985.6 6041.3 6052.8 6058.7 5815.8 5900.5 5923.6 5933.9 5935.2 14481.8 14712.3 14828.0 14883.5 14884.7 2524.7 2564.2 2628.5 2634.7 2663.2 10072.8 10306.8 10992.2 12727.5 14398.8 1884.7 1888.9 1902.1 1923.9 1936.3 80852.6 86129.6 87567.6 88864.0 91139.0 11609.6 11739.9 11872.9 12131.7 12424.6 17449.4 17468.9 17492.3 17500.3 17506.1 7556.7 7605.4 7673.7 7702.0 7762.7 6863.5 6989.4 6991.6 7053.0 7091.7 5029.6 5029.8 5158.4 5391.3 5405.0 2087.4 2144.9 2184.7 2196.1 2228.6 837.3 839.4 840.2 861.4 878.4 18782.9 18863.8 19456.8 20162.7 20220.4 843.7 846.9 848.4 850.5 863.3 10623.3 11138.1 11237.4 11244.7 11246.1 456662.0 460540.0 465533.0 602497.0 622397.0 84357.6 91596.0 93865.0 95173.0 101611.5 1090.1 1127.7 1134.4 1135.3 1140.9 10767.5 10788.5 11249.9 11742.0 11852.3 39951.2 40686.6 44345.3 45877.1 46047.3 64804.7 64879.4 65151.3 65244.0 66384.1 12687.6 13090.7 13174.2 13265.7 13446.5 9057.5 11343.0 14328.0 14568.6 14578.8 12459.8 12526.6 12565.9 12611.6 12786.4 12412.1 12472.4 12560.4 12585.2 13035.7 6875.3 10605.4 12159.8 12633.8 12948.8 12695.2 12749.8 12838.2 12976.7 13030.2 12135.3 12197.9 12299.8 12310.6 12790.3 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#3] mootools.html Cycles:180 Average:5559.17 Median:4842.33 stddev:3187.76 (65.8%) stddev-sans-first:3181.53 08:47:54 INFO - PROCESS | 15564 | Values: 1421.2 1438.2 1456.7 1460.6 1473.5 8284.5 8730.8 8732.6 8735.5 8753.8 8440.2 8622.8 8664.6 8667.5 8873.8 2284.8 2335.6 2336.2 2342.2 2342.3 2275.9 2330.1 2334.6 2338.1 2339.4 2244.6 2270.4 2288.2 2296.6 2299.6 2249.3 2256.1 2265.8 2272.5 2277.6 2215.2 2226.1 2238.8 2239.0 2249.4 8839.0 8908.3 8951.3 8954.4 8956.5 5134.8 5259.2 5264.4 5264.8 5268.9 3118.8 3167.4 3263.8 3330.8 3354.1 1393.4 1402.4 1427.2 1434.1 1468.9 10723.8 10995.8 11536.8 12124.4 12264.3 4405.7 4588.1 4645.0 4673.9 4679.0 10583.6 10587.5 10587.5 10596.7 10606.2 4587.0 4740.8 5305.9 5315.1 5335.6 4667.2 4806.5 4856.1 4857.6 4858.6 3769.7 3878.8 3930.5 3956.5 3998.4 1209.8 1236.0 1243.9 1250.1 1254.4 760.9 773.9 775.8 788.7 792.3 5191.7 5248.0 5263.2 5297.3 5346.0 588.0 588.4 600.8 603.4 606.0 3463.7 3468.3 3541.6 3543.3 3605.2 10338.5 10652.6 10653.9 10665.6 10694.9 9944.3 10185.4 10533.1 10588.4 10603.1 8001.0 8192.1 8210.1 8210.6 8228.6 5204.3 5327.9 5375.1 5427.6 5613.9 9019.6 9282.2 9543.9 9641.9 9653.5 9292.4 9430.5 9669.2 9717.8 9860.7 7345.3 7403.6 7433.5 7455.1 7507.6 8040.4 8088.7 8117.3 8127.8 8152.8 7128.8 7230.3 7232.1 7245.6 7429.8 7112.2 7144.2 7160.1 7197.2 7258.3 6094.9 6159.6 6193.0 6197.5 6204.5 4714.5 4761.9 4763.3 4828.5 4883.9 4647.5 4654.2 4702.2 4782.9 4918.9 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#4] prototype.html Cycles:180 Average:4622.29 Median:5180.30 stddev:1310.28 (25.3%) stddev-sans-first:1274.60 08:47:54 INFO - PROCESS | 15564 | Values: 375.6 400.3 404.3 405.8 408.3 5256.9 5282.2 5335.1 5384.6 5389.7 5035.6 5155.8 5158.7 5167.7 5196.8 4110.9 4144.4 4152.7 4164.3 4175.3 4134.2 4146.7 4156.7 4157.8 4162.7 3911.9 4108.7 4118.6 4138.8 4143.3 4119.4 4130.9 4135.6 4136.4 4136.8 3662.5 3665.8 3698.4 3699.7 3708.9 4870.8 5114.3 5162.7 5184.2 5218.6 4704.2 4707.8 4708.3 4712.5 4713.5 5539.2 5546.0 5557.2 5558.9 5575.3 3801.3 3980.4 4004.0 4014.4 4041.3 5438.6 5544.0 5547.6 5661.3 5714.5 4075.9 4085.1 4103.6 4106.3 4128.2 4092.4 4125.3 4151.9 4157.2 4180.5 4388.5 4497.9 4577.7 4594.9 4667.0 5497.7 5505.8 5515.4 5519.9 5541.6 1468.8 1472.7 1489.0 1509.7 1512.3 1027.4 1078.2 1080.8 1085.9 1101.4 5114.8 5150.6 5156.6 5176.4 5189.9 5473.2 5514.4 5541.0 5583.2 5597.2 4986.5 5134.2 5145.9 5152.7 5152.8 2886.1 2899.6 2903.2 2905.9 2906.6 5520.1 5646.5 5693.8 5699.5 5704.5 5534.7 5543.2 5547.4 5562.9 5563.9 5674.6 5677.0 5678.7 5682.7 5707.2 5199.2 5364.1 5366.3 5369.7 5457.3 5366.3 5388.8 5388.8 5390.4 5418.5 5416.4 5601.8 5690.2 5694.9 5716.7 4280.0 4291.0 4317.8 4329.5 4343.8 5649.3 5685.3 5698.2 5702.5 5724.3 5261.7 5528.6 5534.5 5572.3 5592.9 5509.2 5521.8 5532.8 5533.5 5571.6 5327.6 5545.4 5546.8 5553.8 5569.6 5538.6 5553.3 5561.2 5564.4 5576.0 5435.8 5443.0 5448.8 5493.5 5496.9 08:47:54 INFO - PROCESS | 15564 | 08:47:54 INFO - PROCESS | 15564 | [#5] yui.html Cycles:180 Average:16560.49 Median:599.83 stddev:21599.95 (3601.0%) stddev-sans-first:21627.78 08:47:54 INFO - PROCESS | 15564 | Values: 718.0 744.6 765.7 788.0 808.3 14552.9 15736.2 15745.6 15857.9 15900.6 14457.2 14656.6 14680.8 14681.9 14687.6 760.9 762.8 782.9 783.2 794.3 770.6 770.9 771.2 771.2 771.6 827.4 829.4 829.5 829.6 829.7 821.1 821.7 821.9 822.2 823.2 604.3 604.9 605.2 605.6 606.0 14995.0 15381.6 15583.5 15720.8 15745.0 14294.0 14542.9 14563.3 14734.9 14806.9 11406.0 11475.7 11591.7 11675.6 11687.1 3528.0 3537.1 3574.4 3613.5 3736.2 69242.0 73234.6 73911.3 74305.0 75029.6 10574.8 10770.4 10771.8 10835.1 10877.9 21570.0 21573.4 21578.9 21579.0 21628.5 7096.3 7529.2 7655.7 7666.0 7690.9 4445.7 4448.2 4492.8 4529.0 4614.9 3421.4 3537.6 3540.3 3541.4 3549.9 599.4 604.4 607.8 619.1 632.2 600.3 616.9 619.5 619.9 623.9 8618.4 8762.5 8851.9 9141.4 9397.2 651.8 663.5 670.2 671.5 682.0 4084.9 4337.3 4444.7 4465.8 4530.1 73914.6 76438.2 76551.6 77048.2 77644.0 64467.7 65397.0 65859.7 67999.7 68904.7 59813.5 60126.8 60844.0 62072.7 62086.4 8466.6 8689.3 8772.5 8800.3 8956.0 42454.4 42685.7 42958.8 43195.8 45475.0 49506.3 51860.7 52033.5 52630.5 54189.7 10221.2 10272.2 10361.3 10370.7 10409.2 10975.9 11023.4 11268.4 11526.4 11557.9 10549.9 10827.8 11006.6 11123.9 11383.8 10995.6 11216.3 11467.7 11564.8 11851.2 9846.9 10242.3 10390.2 10425.4 10740.9 8507.9 8702.8 8760.7 8912.7 9011.8 8094.0 8527.4 9036.4 9238.0 9279.4 08:47:54 INFO - PROCESS | 15564 | -------- Summary: end -------- 08:47:54 INFO - PROCESS | 15564 | 08:47:55 INFO - TEST-INFO | 15564: exit 0 08:47:55 INFO - TEST-OK | dromaeo_css | took 1117497ms 08:47:55 INFO - TEST-START | kraken 08:47:55 INFO - operating with platform_type : linux_ 08:47:55 INFO - Initialising browser for kraken test... 08:47:55 INFO - TEST-INFO | started process 15890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGqSYUE/profile http://localhost:49397/getInfo.html) 08:47:58 INFO - PROCESS | 15890 | Iterating over profile /tmp/tmpGqSYUE/profile 08:47:59 INFO - PROCESS | 15890 | __metrics Screen width/height:1600/1200 08:47:59 INFO - PROCESS | 15890 | colorDepth:24 08:47:59 INFO - PROCESS | 15890 | Browser inner width/height: 1024/697 08:47:59 INFO - PROCESS | 15890 | __metrics 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/localstore.rdf = Tue Feb 21 2017 08:28:35 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/safebrowsing = Tue Feb 21 2017 08:47:59 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/extensions.ini = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/cache2 = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/permissions.sqlite = Tue Feb 21 2017 08:47:56 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/sessionCheckpoints.json = Tue Feb 21 2017 08:47:59 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/directoryLinks.json = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/content-prefs.sqlite = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/.parentlock = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/places.sqlite-wal = Tue Feb 21 2017 08:47:59 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/secmod.db = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/blocklist.xml = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/extensions.json = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/bookmarkbackups = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/cert8.db = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/extensions = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/crashes = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/startupCache = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/places.sqlite = Tue Feb 21 2017 08:47:59 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/compatibility.ini = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/user.js = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/key3.db = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/frequencyCap.json = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/places.sqlite-shm = Tue Feb 21 2017 08:47:56 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/pluginreg.dat = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/gmp = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/minidumps = Tue Feb 21 2017 08:47:55 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | Stat failure 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/thumbnails = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/addons.json = Tue Feb 21 2017 08:47:57 GMT-0800 (PST) 08:47:59 INFO - PROCESS | 15890 | No birth date. Using mtime. 08:47:59 INFO - PROCESS | 15890 | Using date: /tmp/tmpGqSYUE/profile/prefs.js = Tue Feb 21 2017 08:28:35 GMT-0800 (PST) 08:48:00 INFO - PROCESS | 15890 | 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" 08:48:00 INFO - PROCESS | 15890 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:48:01 INFO - TEST-INFO | 15890: exit 0 08:48:01 INFO - Browser initialized. 08:48:01 INFO - Running cycle 1/1 for kraken test... 08:48:01 INFO - TEST-INFO | started process 15981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGqSYUE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:13 INFO - PROCESS | 15981 | RSS: Main: 143425536 08:48:13 INFO - PROCESS | 15981 | 08:48:15 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-beat-detection) 08:48:16 INFO - PROCESS | 15981 | RSS: Main: 198393856 08:48:16 INFO - PROCESS | 15981 | 08:48:19 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-dft) 08:48:20 INFO - PROCESS | 15981 | RSS: Main: 177344512 08:48:20 INFO - PROCESS | 15981 | 08:48:22 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-fft) 08:48:23 INFO - PROCESS | 15981 | RSS: Main: 259166208 08:48:23 INFO - PROCESS | 15981 | 08:48:25 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-oscillator) 08:48:26 INFO - PROCESS | 15981 | RSS: Main: 170586112 08:48:26 INFO - PROCESS | 15981 | 08:48:28 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:48:28 INFO - PROCESS | 15981 | RSS: Main: 174252032 08:48:28 INFO - PROCESS | 15981 | 08:48:31 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-darkroom) 08:48:32 INFO - PROCESS | 15981 | RSS: Main: 213753856 08:48:32 INFO - PROCESS | 15981 | 08:48:35 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-desaturate) 08:48:36 INFO - PROCESS | 15981 | RSS: Main: 214274048 08:48:36 INFO - PROCESS | 15981 | 08:48:39 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49397/tests/kraken/driver.html?testName=json-parse-financial) 08:48:39 INFO - PROCESS | 15981 | RSS: Main: 212729856 08:48:39 INFO - PROCESS | 15981 | 08:48:41 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49397/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:48:41 INFO - PROCESS | 15981 | RSS: Main: 174931968 08:48:41 INFO - PROCESS | 15981 | 08:48:43 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:48:44 INFO - PROCESS | 15981 | RSS: Main: 182161408 08:48:44 INFO - PROCESS | 15981 | 08:48:46 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:48:47 INFO - PROCESS | 15981 | RSS: Main: 190681088 08:48:47 INFO - PROCESS | 15981 | 08:48:49 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:48:50 INFO - PROCESS | 15981 | RSS: Main: 199507968 08:48:50 INFO - PROCESS | 15981 | 08:48:53 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:48:53 INFO - PROCESS | 15981 | RSS: Main: 196845568 08:48:53 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49397/tests/kraken/driver.html?testName=ai-astar) 08:48:55 INFO - PROCESS | 15981 | RSS: Main: 249253888 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | __start_tp_report 08:48:55 INFO - PROCESS | 15981 | _x_x_mozilla_page_load 08:48:55 INFO - PROCESS | 15981 | _x_x_mozilla_page_load_details 08:48:55 INFO - PROCESS | 15981 | |i|pagename|runs| 08:48:55 INFO - PROCESS | 15981 | |0;ai-astar;112;114;115;112;114;114;114;113;113;114 08:48:55 INFO - PROCESS | 15981 | |1;audio-beat-detection;149;122;124;178;120;121;122;121;140;120 08:48:55 INFO - PROCESS | 15981 | |2;audio-dft;162;161;161;161;162;163;162;162;163;162 08:48:55 INFO - PROCESS | 15981 | |3;audio-fft;74;75;76;111;77;76;78;75;91;76 08:48:55 INFO - PROCESS | 15981 | |4;audio-oscillator;83;84;115;83;83;84;102;84;84;111 08:48:55 INFO - PROCESS | 15981 | |5;imaging-gaussian-blur;114;113;113;114;113;114;114;113;113;113 08:48:55 INFO - PROCESS | 15981 | |6;imaging-darkroom;147;147;147;147;148;147;147;147;147;146 08:48:55 INFO - PROCESS | 15981 | |7;imaging-desaturate;97;96;96;96;95;96;96;95;96;96 08:48:55 INFO - PROCESS | 15981 | |8;json-parse-financial;56;56;56;57;57;58;57;55;56;57 08:48:55 INFO - PROCESS | 15981 | |9;json-stringify-tinderbox;65;64;64;64;64;63;67;64;64;64 08:48:55 INFO - PROCESS | 15981 | |10;stanford-crypto-aes;82;83;91;83;82;83;83;84;88;83 08:48:55 INFO - PROCESS | 15981 | |11;stanford-crypto-ccm;138;134;134;131;142;129;130;129;170;138 08:48:55 INFO - PROCESS | 15981 | |12;stanford-crypto-pbkdf2;170;172;169;170;169;172;172;170;167;169 08:48:55 INFO - PROCESS | 15981 | |13;stanford-crypto-sha256-iterative;66;64;63;63;71;64;63;67;64;66 08:48:55 INFO - PROCESS | 15981 | __end_tp_report 08:48:55 INFO - PROCESS | 15981 | __start_cc_report 08:48:55 INFO - PROCESS | 15981 | _x_x_mozilla_cycle_collect,1718 08:48:55 INFO - PROCESS | 15981 | __end_cc_report 08:48:55 INFO - PROCESS | 15981 | __startTimestamp1487695735801__endTimestamp 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | ------- Summary: start ------- 08:48:55 INFO - PROCESS | 15981 | Number of tests: 14 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:0.97 (0.9%) stddev-sans-first:0.87 08:48:55 INFO - PROCESS | 15981 | Values: 112.0 114.0 115.0 112.0 114.0 114.0 114.0 113.0 113.0 114.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:123.00 stddev:19.04 (15.5%) stddev-sans-first:19.14 08:48:55 INFO - PROCESS | 15981 | Values: 149.0 122.0 124.0 178.0 120.0 121.0 122.0 121.0 140.0 120.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:48:55 INFO - PROCESS | 15981 | Values: 162.0 161.0 161.0 161.0 162.0 163.0 162.0 162.0 163.0 162.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:11.65 (15.3%) stddev-sans-first:12.08 08:48:55 INFO - PROCESS | 15981 | Values: 74.0 75.0 76.0 111.0 77.0 76.0 78.0 75.0 91.0 76.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:83.50 stddev:12.84 (15.4%) stddev-sans-first:13.26 08:48:55 INFO - PROCESS | 15981 | Values: 83.0 84.0 115.0 83.0 83.0 84.0 102.0 84.0 84.0 111.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:48:55 INFO - PROCESS | 15981 | Values: 114.0 113.0 113.0 114.0 113.0 114.0 114.0 113.0 113.0 113.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 08:48:55 INFO - PROCESS | 15981 | Values: 147.0 147.0 147.0 147.0 148.0 147.0 147.0 147.0 147.0 146.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 08:48:55 INFO - PROCESS | 15981 | Values: 97.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 08:48:55 INFO - PROCESS | 15981 | Values: 56.0 56.0 56.0 57.0 57.0 58.0 57.0 55.0 56.0 57.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:1.06 (1.7%) stddev-sans-first:1.09 08:48:55 INFO - PROCESS | 15981 | Values: 65.0 64.0 64.0 64.0 64.0 63.0 67.0 64.0 64.0 64.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:83.00 stddev:2.94 (3.5%) stddev-sans-first:3.00 08:48:55 INFO - PROCESS | 15981 | Values: 82.0 83.0 91.0 83.0 82.0 83.0 83.0 84.0 88.0 83.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:136.00 stddev:12.22 (9.0%) stddev-sans-first:12.96 08:48:55 INFO - PROCESS | 15981 | Values: 138.0 134.0 134.0 131.0 142.0 129.0 130.0 129.0 170.0 138.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.00 stddev:1.63 (1.0%) stddev-sans-first:1.73 08:48:55 INFO - PROCESS | 15981 | Values: 170.0 172.0 169.0 170.0 169.0 172.0 172.0 170.0 167.0 169.0 08:48:55 INFO - PROCESS | 15981 | 08:48:55 INFO - PROCESS | 15981 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:2.51 (3.9%) stddev-sans-first:2.65 08:48:55 INFO - PROCESS | 15981 | Values: 66.0 64.0 63.0 63.0 71.0 64.0 63.0 67.0 64.0 66.0 08:48:55 INFO - PROCESS | 15981 | -------- Summary: end -------- 08:48:55 INFO - PROCESS | 15981 | 08:48:56 INFO - TEST-INFO | 15981: exit 0 08:48:56 INFO - TEST-OK | kraken | took 61521ms 08:48:56 INFO - SUITE-END | took 1179s 08:48:56 INFO - Completed test suite (00:19:39) 08:48:56 INFO - javascript benchmark 08:48:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7063.353669634978, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.795523290987, 3159.9368012639748, 3184.4586420308547, 3193.3134376109147, 3211.534327522511, 5228.451012643674, 5320.0, 5325.813596524123, 5365.624406291561, 5383.16560933055, 5347.278117454142, 5359.732013399324, 5459.071957767179, 5460.5085542301185, 5499.697516636584, 4889.779959901813, 4920.089783390066, 4938.962817808256, 5035.3454050973505, 5041.3194218780545, 5010.423801262847, 5024.4724303948105, 5094.923576146342, 5100.209467532539, 5129.871753206163, 4790.401199850019, 4817.975910120427, 4820.493848145929, 4896.192128298826, 5023.422306434752, 4883.804647814083, 4891.657583969123, 4896.3389942358035, 4914.238293064581, 4966.776495057731, 4939.333190019337, 4945.109880221558, 4982.60139188864, 4990.725510096943, 5022.89954200914, 5412.431694672082, 5418.431064738224, 5439.075357189287, 5447.346318441772, 5512.3385193777, 5545.085060964976, 5579.804706835242, 5796.333421656479, 5799.681017544033, 5800.680962547058, 5617.0731829248125, 5637.408072152447, 5664.575156863251, 5675.602707810455, 5683.829485115453, 5288.788448462095, 5298.364196296469, 5300.628955973083, 5302.337207849019, 5304.257403963448, 5724.713764311768, 5780.930527852361, 5782.624129431573, 5791.0734282514595, 5791.305043394736, 5538.030844602178, 5550.361708403505, 5671.064274394678, 5721.199032135504, 5729.7708091676695, 5775.768969241183, 5785.82642520725, 5793.131030345482, 5800.361960184377, 5836.358000619928, 5753.88492230153, 5763.337216220189, 5764.625299355528, 5769.451902069297, 5770.682612456355, 5569.359523654751, 5598.552115830724, 5606.6636001840025, 5658.547316214693, 5762.510186634183, 4399.64802815774, 4442.666799990014, 4452.9554704453185, 4469.150861336336, 4510.684252102387, 3997.620226078518, 3999.92000160001, 4006.358982562776, 4024.1549274652066, 4040.919181616351, 5511.3937466878615, 5520.558355331564, 5521.0, 5522.834314970557, 5567.025770490148, 5591.30108736408, 5636.0, 5642.210090587321, 5701.543876489871, 5866.1494083357675, 5383.973080134652, 5431.131019036935, 5452.563794896399, 5490.615656904059, 5554.0, 4966.056449274626, 4966.503349665005, 4966.6771659842, 4982.153033984231, 5004.074246264453, 6037.1246169305205, 6041.063635136561, 6079.75680972765, 6092.482139018233, 6099.237595300588, 5694.345150307602, 5754.367019627922, 5757.5681823864215, 5798.10129429939, 5799.884002319845, 6029.0, 6050.576459647782, 6068.908966365596, 6099.340048627855, 6109.040306147876, 5730.512906402836, 5731.770729170953, 5751.626144300607, 5753.626014309057, 5787.7395517200985, 5612.550995920398, 5812.157237200667, 5860.824175274749, 5867.237259156283, 5869.442402971711, 5535.806246781504, 5594.608377413705, 5599.440055994531, 5621.775128994795, 5640.125780504029, 5679.176519404746, 5679.176519404746, 5681.602287839977, 5712.742926568398, 5733.168690539932, 5667.574931880043, 5669.716514174357, 5669.744861481161, 5670.347909990239, 5674.092145256739, 5563.916541251803, 5614.326280846324, 5617.859553511195, 5659.518940890071, 5697.601167918206, 5472.835814925558, 5494.2033405155225, 5511.834644960658, 5517.61376703643, 5522.282103326542], "value": 5329.29309107433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.2221659601761, 597.4533302028644, 600.9008513595257, 602.1209034809178, 602.4246844263727, 29896.850515747417, 30403.69596304036, 42780.35829462557, 43938.0, 44065.0, 42343.36484952728, 45750.54249457504, 45758.31362529565, 45761.0, 45908.77045614767, 8993.999999999984, 9170.312226582999, 9194.26445884331, 9197.862032069525, 9226.169644732006, 7352.779416617484, 7515.0, 8935.955320223422, 10559.26085174038, 10786.406747628876, 10035.147012503947, 10090.747731306703, 10090.74773130674, 10126.341787783807, 10153.187744980421, 10142.340747851404, 10188.898111018943, 10195.286329956905, 10195.337303075275, 10208.744781380525, 10609.575616975311, 10636.893631063667, 10642.201834862417, 10668.679939601825, 10689.358638481715, 42257.57742422598, 42404.57595424037, 42503.36244956328, 42654.0, 42658.57341426577, 3716.2939041582285, 3816.1985982943615, 3828.4257361395735, 3874.728768986172, 3877.495925529663, 30862.22844428885, 30896.84551577228, 30903.0, 31344.276064816426, 31459.0, 3950.6839452843706, 4063.878083657495, 4083.0, 4092.283850326181, 4221.852235171785, 301816.0, 302165.0, 302634.0, 302738.0, 303991.0, 56588.43411565832, 56766.0, 56896.715516422155, 57046.42953570494, 57100.42899571034, 60799.392006080256, 60993.695031524556, 61091.38908610946, 61109.69445152835, 61163.0, 13992.30038498071, 14191.858081419054, 14191.858081419261, 14201.644958875904, 14219.786703199461, 9448.716538503855, 10742.409167495864, 10783.083437907866, 10785.622503212508, 10834.458277086114, 13671.521496747566, 17431.63779941137, 21006.78993210079, 21351.67972480414, 21761.782382176294, 7412.4440666950195, 7462.888056679154, 7582.469227154046, 7617.542947423172, 8066.435349525595, 854.5812551849673, 884.7832281091174, 893.6112790935833, 899.321012635456, 928.2852203803169, 30194.54708179379, 30606.54090188649, 30705.69294307073, 30710.38579228403, 31161.351285140565, 1036.0934182590233, 1037.8339465685606, 1044.556063672936, 1062.2245760594808, 1178.5874943769784, 15016.474423395348, 15809.209539522977, 16069.51791446235, 16172.75740863888, 16540.83459165417, 467597.0, 467895.0, 468621.0, 470665.0, 471904.0, 60631.39368606346, 60841.69579152076, 61098.69450652718, 61157.69421152866, 61210.69394652998, 1316.1905428161804, 1353.093427403642, 1357.2942070123481, 1379.7585422551012, 1397.2524699285834, 28240.24552388766, 28827.56758648664, 29482.410351793274, 29897.850510747307, 30278.09165725032, 50034.49965500298, 51080.23379649383, 51225.23162152645, 51389.48610513847, 51433.0, 404052.0, 404426.0, 405962.0, 406086.0, 406711.0, 23939.76060239375, 24494.0, 27411.862940685965, 27948.58077128804, 27954.16137515878, 42801.35797963096, 47757.0, 48253.0, 48309.067637294116, 48629.0, 38336.2332753349, 38851.80574097111, 39072.0, 39272.60727392689, 40652.292371758325, 38302.063180207115, 38539.229215414976, 39613.0, 39933.0, 40077.398839018024, 38474.0, 38575.6142438572, 38783.22433551257, 38937.6106238934, 39651.2069758609, 31788.364232714754, 32727.18182045468, 33667.15832104217, 33836.66163338335, 34383.4842477358, 26154.607680884423, 26517.60223596686, 26689.33276668099, 26722.59916101299, 26819.463610727285], "value": 19570.669012099184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.99373009404866, 418.7633986797457, 424.65815018909785, 435.36436802268685, 450.5517010574479, 19598.0, 19662.213511459544, 19955.600887982233, 20046.49651276153, 20121.899390503044, 18558.35045773398, 19458.610827783436, 20916.0, 21029.474263143427, 21190.152393904224, 5705.914411283824, 5939.2575928009, 5964.701764911759, 5977.252843394575, 5995.250593675791, 5685.800996965107, 5827.970860145693, 5868.2664666916635, 5919.230500035011, 5965.045592705168, 6039.667818269993, 6042.667653279068, 6050.092486127072, 6050.6369617823075, 6051.2133422655, 5960.254968128984, 5985.6408615483, 6041.335453100155, 6052.848678783022, 6058.697065146725, 5815.82552523425, 5900.498457631106, 5923.644581325134, 5933.8813223735715, 5935.198748168985, 14481.78277325841, 14712.264386780726, 14828.036177648419, 14883.47907823221, 14884.702305953928, 2524.6591710119264, 2564.217913536371, 2628.487444948236, 2634.7233540478164, 2663.240976321739, 10072.848907266396, 10306.845397319046, 10992.230543861933, 12727.490900363975, 14398.784018239734, 1884.6513395021964, 1888.8772229805018, 1902.0965041605127, 1923.8941858197793, 1936.2545420013396, 80852.59573702153, 86129.56935215347, 87567.56216218878, 88864.0, 91139.0, 11609.593664221711, 11739.882601173878, 11872.881271187178, 12131.696707582381, 12424.56514022005, 17449.389271375694, 17468.912655436896, 17492.300307987793, 17500.299988000592, 17506.12469376526, 7556.65995030225, 7605.429592780453, 7673.693052277958, 7702.037245344332, 7762.728304509315, 6863.450923926074, 6989.405900498515, 6991.580505169604, 7053.0, 7091.680874360666, 5029.622778291643, 5029.84910452687, 5158.3552055993005, 5391.32608423947, 5404.972975135099, 2087.4363921741347, 2144.946376340573, 2184.650455927044, 2196.1325276515686, 2228.598852206586, 837.2590257622048, 839.4249938791947, 840.1892174052069, 861.401326078382, 878.4246318661287, 18782.90608546976, 18863.811361886204, 19456.805431945784, 20162.69755953662, 20220.39338819838, 843.679401827314, 846.9153084691482, 848.4357901995228, 850.5279569838799, 863.2921004776024, 10623.309484883457, 11138.108951283877, 11237.381943992996, 11244.66266012021, 11246.100311975022, 456662.0, 460540.0, 465533.0, 602497.0, 622397.0, 84357.57821210855, 91596.0, 93865.0, 95173.0, 101611.49194253981, 1090.0679918669423, 1127.672974837288, 1134.444122380028, 1135.3301552084145, 1140.9201355905006, 10767.461626918466, 10788.460576970963, 11249.943750281196, 11742.0, 11852.348120853436, 39951.2009759809, 40686.593134069466, 44345.33481997837, 45877.08245834998, 46047.309290360005, 64804.675976619816, 64879.40482380841, 65151.34848651453, 65244.0, 66384.0847745684, 12687.619371418872, 13090.672733181747, 13174.209547427185, 13265.66835829112, 13446.46214151423, 9057.501837399006, 11342.97913187817, 14328.0, 14568.635784105481, 14578.781318280446, 12459.813102803284, 12526.624201273977, 12565.87434125647, 12611.558595449114, 12786.424610892718, 12412.069094817743, 12472.438740256528, 12560.43478043472, 12585.231249125178, 13035.739285214433, 6875.312468753084, 10605.41670208146, 12159.75680486403, 12633.778386511382, 12948.8057679133, 12695.238285702888, 12749.808752868528, 12838.229706217657, 12976.740465190833, 13030.153040052366, 12135.271883687006, 12197.878021219674, 12299.815502767644, 12310.630681079581, 12790.296533690736], "value": 9496.851049371318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.1686163594297, 1438.2449214162564, 1456.6868123353483, 1460.5618314505646, 1473.5358362115933, 8284.544350060743, 8730.825383492327, 8732.607032683529, 8735.519546424952, 8753.781155471117, 8440.240378365947, 8622.784430389243, 8664.610092545834, 8667.479951202933, 8873.778155546115, 2284.7715228477186, 2335.649652552114, 2336.1589827662115, 2342.203650758742, 2342.320726989171, 2275.943101422461, 2330.0912644068826, 2334.5681049005902, 2338.1348900906605, 2339.3683705399617, 2244.62963611004, 2270.409693479699, 2288.1762565476415, 2296.5636529059425, 2299.5975704251773, 2249.3139592424304, 2256.131389415079, 2265.762094980028, 2272.5000499889998, 2277.5900337939165, 2215.2468160825397, 2226.053927080984, 2238.832087593437, 2239.0260236797044, 2249.392663980716, 8839.02770695223, 8908.287337013024, 8951.283897288202, 8954.417962832395, 8956.462612243286, 5134.820281290137, 5259.211118332242, 5264.420913699529, 5264.763085661154, 5268.947310526923, 3118.750499959998, 3167.4298626247264, 3263.8368081595827, 3330.81680507572, 3354.111160542458, 1393.3799459240643, 1402.3689339797131, 1427.1936355958953, 1434.0535246737209, 1468.8971771975966, 10723.785524289471, 10995.835062474069, 11536.76926461466, 12124.39378031095, 12264.325462099581, 4405.66957478184, 4588.105319462739, 4645.0, 4673.859784206479, 4678.976605117021, 10583.57665693379, 10587.470626468647, 10587.523561439753, 10596.682099537025, 10606.204534659932, 4586.954130458719, 4740.786664600101, 5305.893882122413, 5315.123004704181, 5335.599830012765, 4667.183242932541, 4806.543378379049, 4856.125897338442, 4857.611391088704, 4858.58702010326, 3769.717271204616, 3878.8448462061765, 3930.5283365996265, 3956.5252169739815, 3998.3602623580086, 1209.8124790657462, 1235.975280494385, 1243.9378031098408, 1250.056207563302, 1254.4355040231933, 760.9124950630637, 773.9187385324573, 775.7595145504912, 788.6569342336102, 792.3304807437706, 5191.740412979336, 5247.973760131251, 5263.157894736823, 5297.258383826267, 5346.0, 587.9882402351928, 588.3616276340133, 600.798732424634, 603.4448307557058, 606.0333767640694, 3463.7229021678204, 3468.3410152071024, 3541.592716837545, 3543.3267679141395, 3605.2068544920076, 10338.534765935401, 10652.62715804943, 10653.893461065445, 10665.573377064831, 10694.946525267324, 9944.30389872702, 10185.388876667424, 10533.052025317758, 10588.364698118137, 10603.098736607475, 8001.0, 8192.057913340042, 8210.096889342287, 8210.58947052657, 8228.62971166299, 5204.2714020036465, 5327.920081198863, 5375.113106337567, 5427.592930530305, 5613.915791263053, 9019.594118264562, 9282.164605185566, 9543.856842147512, 9641.903580964381, 9653.46905920153, 9292.442453452815, 9430.528473576156, 9669.226461883172, 9717.757056073655, 9860.704178874645, 7345.265473452612, 7403.629818509161, 7433.479656423999, 7455.142658594114, 7507.587082710504, 8040.356771458152, 8088.676452941816, 8117.269445749911, 8127.75616731499, 8152.796180095545, 7128.750493732695, 7230.276972302728, 7232.132144142736, 7245.5652660840515, 7429.777106686808, 7112.217656057933, 7144.214136444883, 7160.10498687664, 7197.172325182671, 7258.274172582699, 6094.939050609614, 6159.630422174684, 6193.0, 6197.504199663926, 6204.534659900435, 4714.528547145258, 4761.880952976065, 4763.261694437368, 4828.5412885775795, 4883.853484395474, 4647.535246475325, 4654.185517534485, 4702.247640377524, 4782.904341913073, 4918.852434426973], "value": 4412.106683696141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.64876840154443, 400.27350359098244, 404.31065034116835, 405.84780707234785, 408.31607058177565, 5256.894862102756, 5282.181261904401, 5335.119705248634, 5384.6499977501435, 5389.676619402833, 5035.622328325381, 5155.7679904404295, 5158.664686795365, 5167.6641018333785, 5196.818111366103, 4110.938335924956, 4144.399062135988, 4152.73007254529, 4164.333706606943, 4175.31107367284, 4134.173165366924, 4146.709730318879, 4156.709030367875, 4157.792110394468, 4162.708610397273, 3911.8630847920326, 4108.691848111389, 4118.649914757234, 4138.834446622147, 4143.337066069429, 4119.423280740669, 4130.876073717778, 4135.560019588427, 4136.400221967828, 4136.772477513736, 3662.4506324051604, 3665.7617254878346, 3698.3527882620433, 3699.741018128705, 3708.9443658345144, 4870.7564621769125, 5114.335136432277, 5162.683515703471, 5184.196449550288, 5218.617066477386, 4704.223803072489, 4707.7881495332795, 4708.317293992386, 4712.481627021025, 4713.528647135293, 5539.19681646159, 5545.972270138664, 5557.221988921554, 5558.888222355545, 5575.275214222166, 3801.3347664158937, 3980.383040628707, 4003.959960400388, 4014.41790940312, 4041.2523683118716, 5438.619296649236, 5544.0, 5547.55619550439, 5661.34894487131, 5714.457126572969, 4075.9388609170887, 4085.142120154801, 4103.610157035077, 4106.281400754916, 4128.174365126986, 4092.4066010428323, 4125.257453658371, 4151.916961660811, 4157.230912281238, 4180.498340199196, 4388.539203383662, 4497.865064048084, 4577.7482238477205, 4594.862154135314, 4666.953330466652, 5497.697626630574, 5505.752241149078, 5515.393306736256, 5519.868426972541, 5541.556675465953, 1468.7723402872573, 1472.7201831651953, 1489.0395694776807, 1509.679030848008, 1512.289224064688, 1027.4092396871679, 1078.1751959750798, 1080.7841178673991, 1085.934843909368, 1101.3942331717524, 5114.8235905741885, 5150.613703972217, 5156.61325400589, 5176.4047134580005, 5189.896202075937, 5473.233747275384, 5514.448555144453, 5540.972295138498, 5583.218349431083, 5597.244372009808, 4986.526280003466, 5134.204198349262, 5145.9485405146215, 5152.742362881841, 5152.819651312186, 2886.134159752066, 2899.6230490036583, 2903.245156259388, 2905.898293559715, 2906.5785461108444, 5520.095567029369, 5646.463585959327, 5693.772249110072, 5699.499982516808, 5704.515116215168, 5534.695591742493, 5543.223948647193, 5547.41752116022, 5562.86092847674, 5563.916541251884, 5674.574406919498, 5676.984670442016, 5678.744456499385, 5682.687452190169, 5707.229524014205, 5199.246109314053, 5364.114921038141, 5366.275552800322, 5369.731513424391, 5457.263269458573, 5366.302380690641, 5388.784448622011, 5388.841399099212, 5390.434004429479, 5418.539424148991, 5416.4041955385665, 5601.815292036191, 5690.154922538731, 5694.914576281442, 5716.742746576331, 4279.99420136274, 4290.978545107255, 4317.8272869084885, 4329.467261651969, 4343.804528796149, 5649.293838270216, 5685.34618518859, 5698.230738850202, 5702.486776190163, 5724.255846739898, 5261.736913154404, 5528.557715382679, 5534.520824383751, 5572.331320241597, 5592.888142237214, 5509.201165831012, 5521.779128834802, 5532.751026203911, 5533.474319939599, 5571.582131340247, 5327.573794096541, 5545.417731138173, 5546.805861794818, 5553.8333849984565, 5569.55443564522, 5538.6399884007415, 5553.278073850373, 5561.192612400905, 5564.443555644403, 5576.0241957656435, 5435.83692489226, 5443.020256353736, 5448.83653490396, 5493.5442107842255, 5496.890062198654], "value": 4233.333396719627, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.989230161548, 744.5867543513351, 765.7319938021693, 787.9881801772973, 808.3129340060947, 14552.92723536382, 15736.213189340517, 15745.60635984101, 15857.920710396433, 15900.602484937868, 14457.204853733041, 14656.633584160401, 14680.779788303184, 14681.853181468183, 14687.63280917975, 760.8668483015464, 762.7635433015755, 782.9491083079581, 783.1933108897816, 794.3486341200218, 770.6185438208067, 770.8728059870115, 771.1632878327022, 771.240328276647, 771.5679219636993, 827.3753316246226, 829.3945419843518, 829.5396055189367, 829.618375547249, 829.6764261937849, 821.1172989036779, 821.6549049399237, 821.9054808697017, 822.2065706593166, 823.2097186700775, 604.2537466229203, 604.8760004199129, 605.1769593353036, 605.6366180291834, 605.9848503787397, 14995.0, 15381.615459613487, 15583.532494025198, 15720.764188537181, 15745.0, 14294.0, 14542.854571454256, 14563.271836408137, 14734.926325368304, 14806.925965370105, 11406.0, 11475.713107172303, 11591.6522504325, 11675.591354302644, 11687.065034797195, 3527.982360088183, 3537.1157210697324, 3574.410222313315, 3613.4579813027995, 3736.23407201523, 69242.0, 73234.63382683106, 73911.26088739098, 74305.0, 75029.62485187594, 10574.788504229873, 10770.353778773298, 10771.78456430867, 10835.079018283377, 10877.891221087688, 21570.0, 21573.352799416043, 21578.892105539373, 21579.028943697256, 21628.45928851791, 7096.290370962863, 7529.171791102975, 7655.732049378245, 7666.041744781903, 7690.923090769021, 4445.666575006888, 4448.243798554254, 4492.775361231926, 4528.954710452919, 4614.861554153381, 3421.4354631486026, 3537.6108628050893, 3540.256546125291, 3541.3979623464074, 3549.893503194908, 599.3586862057643, 604.4348534120611, 607.7994261893565, 619.1301221783401, 632.2192092765431, 600.3006497430445, 616.9259688837368, 619.4827319188438, 619.8574327904607, 623.8783437229697, 8618.353623478264, 8762.518061506678, 8851.867221991675, 9141.436157565184, 9397.248220142372, 651.7849109793757, 663.4891133826926, 670.168990451846, 671.4997326991422, 681.9556728812611, 4084.8570300039355, 4337.284348082531, 4444.711093778894, 4465.754383508874, 4530.141492608341, 73914.6304268486, 76438.23561764423, 76551.61724191454, 77048.22951770523, 77644.0, 64467.67766161139, 65397.0, 65859.67070164619, 67999.66000169968, 68904.6554767223, 59813.541463804475, 60126.784785825104, 60843.99999999911, 62072.68963655153, 62086.37913620896, 8466.619002144795, 8689.261412779731, 8772.473651580925, 8800.339974502065, 8956.0, 42454.363184551636, 42685.719428415956, 42958.78520607502, 43195.78402107969, 45475.0, 49506.25740613822, 51860.74069629628, 52033.47966520286, 52630.47369526256, 54189.729051354494, 10221.23340749432, 10272.178225742073, 10361.326513776881, 10370.740731481774, 10409.1672666185, 10975.890241097486, 11023.441739139105, 11268.436578171222, 11526.366049867338, 11557.929137937745, 10549.947250264007, 10827.783444330911, 11006.559737610727, 11123.944380278046, 11383.829242561535, 10995.615153469591, 11216.270942389045, 11467.655970320904, 11564.826527601925, 11851.229670071418, 9846.885684554893, 10242.334248273839, 10390.22073344487, 10425.426601536989, 10740.946295268473, 8507.872381914152, 8702.782430439036, 8760.693375731818, 8912.688055918012, 9011.774705632411, 8093.959530202312, 8527.360447966552, 9036.412633178823, 9238.0, 9279.350445468752], "value": 6711.75534069889, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1513.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 112.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 124.0, 178.0, 120.0, 121.0, 122.0, 121.0, 140.0, 120.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 163.0, 162.0, 162.0, 163.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 111.0, 77.0, 76.0, 78.0, 75.0, 91.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 83.0, 83.0, 84.0, 102.0, 84.0, 84.0, 111.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 57.0, 58.0, 57.0, 55.0, 56.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 64.0, 63.0, 67.0, 64.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 91.0, 83.0, 82.0, 83.0, 83.0, 84.0, 88.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 134.0, 131.0, 142.0, 129.0, 130.0, 129.0, 170.0, 138.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 169.0, 170.0, 169.0, 172.0, 172.0, 170.0, 167.0, 169.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 63.0, 63.0, 71.0, 64.0, 63.0, 67.0, 64.0, 66.0], "value": 65.1, "unit": "score"}]}]} 08:48:57 INFO - Return code: 0 08:48:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:48:57 ERROR - # TBPL SUCCESS # 08:48:57 INFO - Running post-action listener: _resource_record_post_action 08:48:57 INFO - [mozharness: 2017-02-21 16:48:57.130445Z] Finished run-tests step (success) 08:48:57 INFO - Running post-run listener: _resource_record_post_run 08:48:57 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 364093440; Read time: 8; Write time: 261576 08:48:57 INFO - TinderboxPrint: CPU usage
13.1% 08:48:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:48:57 INFO - TinderboxPrint: I/O write bytes / time
364,093,440 / 261,576 08:48:57 INFO - TinderboxPrint: CPU idle
8,295.0 (86.9%) 08:48:57 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 08:48:57 INFO - TinderboxPrint: Swap in / out
0 / 0 08:48:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112893952; Read time: 0; Write time: 103416 08:48:57 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251199488; Read time: 8; Write time: 158160 08:48:57 INFO - Running post-run listener: _upload_blobber_files 08:48:57 INFO - Blob upload gear active. 08:48:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:48:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:48:58 INFO - (blobuploader) - INFO - Open directory for files ... 08:48:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:48:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:48:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:58 INFO - (blobuploader) - INFO - Done attempting. 08:48:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:48:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:48:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:59 INFO - (blobuploader) - INFO - Done attempting. 08:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:03 INFO - (blobuploader) - INFO - Done attempting. 08:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:03 INFO - (blobuploader) - INFO - Done attempting. 08:49:03 INFO - (blobuploader) - INFO - Iteration through files over. 08:49:03 INFO - Return code: 0 08:49:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:49:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:49:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/818a90b3e112e7b4d1a17a69c56f9293a49551c5f4f147591e1e1c7a4c2e8d0dde35b5f98f82d09aad62832dcebc7d7b4de679d51ee89d9b956c295eaa4e258e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/371e8408e5c3f7f6f0c3f0f3bd69cfe61c93a4fc09aba95e45d54bba9ce7f4b11cba770346f7eca80bc5d9609c542cf15ed24f7e55abaeda22970de59fc1fecd", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b875d416f9c8638de726e366a444aefe9cc975425f66d4b96f6ccf26db005092bb20002dd19bd5db48ed2278c8daf626efc669e9e1e8e2c71ff69d5aa937c119", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66168ca69ba0b9737cbb469f34c99b9bf201fc3b6d46bc8c1d76b17a4630376552a9df4cb5ea6e66421178ca1a84bf659857ce317e964ec8f84caef35b96488"} 08:49:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:49:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:49:03 INFO - Contents: 08:49:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/818a90b3e112e7b4d1a17a69c56f9293a49551c5f4f147591e1e1c7a4c2e8d0dde35b5f98f82d09aad62832dcebc7d7b4de679d51ee89d9b956c295eaa4e258e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/371e8408e5c3f7f6f0c3f0f3bd69cfe61c93a4fc09aba95e45d54bba9ce7f4b11cba770346f7eca80bc5d9609c542cf15ed24f7e55abaeda22970de59fc1fecd", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b875d416f9c8638de726e366a444aefe9cc975425f66d4b96f6ccf26db005092bb20002dd19bd5db48ed2278c8daf626efc669e9e1e8e2c71ff69d5aa937c119", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66168ca69ba0b9737cbb469f34c99b9bf201fc3b6d46bc8c1d76b17a4630376552a9df4cb5ea6e66421178ca1a84bf659857ce317e964ec8f84caef35b96488"} 08:49:03 INFO - Running post-run listener: copy_logs_to_upload_dir 08:49:03 INFO - Copying logs to upload dir... 08:49:03 INFO - mkdir: /builds/slave/test/build/upload/logs 08:49:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.054603 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-21 08:49:03.728051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 08:49:03.731253) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/818a90b3e112e7b4d1a17a69c56f9293a49551c5f4f147591e1e1c7a4c2e8d0dde35b5f98f82d09aad62832dcebc7d7b4de679d51ee89d9b956c295eaa4e258e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/371e8408e5c3f7f6f0c3f0f3bd69cfe61c93a4fc09aba95e45d54bba9ce7f4b11cba770346f7eca80bc5d9609c542cf15ed24f7e55abaeda22970de59fc1fecd", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b875d416f9c8638de726e366a444aefe9cc975425f66d4b96f6ccf26db005092bb20002dd19bd5db48ed2278c8daf626efc669e9e1e8e2c71ff69d5aa937c119", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66168ca69ba0b9737cbb469f34c99b9bf201fc3b6d46bc8c1d76b17a4630376552a9df4cb5ea6e66421178ca1a84bf659857ce317e964ec8f84caef35b96488"} build_url:https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/UW-aSpgdSu6okAW1GMeBsA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/818a90b3e112e7b4d1a17a69c56f9293a49551c5f4f147591e1e1c7a4c2e8d0dde35b5f98f82d09aad62832dcebc7d7b4de679d51ee89d9b956c295eaa4e258e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/371e8408e5c3f7f6f0c3f0f3bd69cfe61c93a4fc09aba95e45d54bba9ce7f4b11cba770346f7eca80bc5d9609c542cf15ed24f7e55abaeda22970de59fc1fecd", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b875d416f9c8638de726e366a444aefe9cc975425f66d4b96f6ccf26db005092bb20002dd19bd5db48ed2278c8daf626efc669e9e1e8e2c71ff69d5aa937c119", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66168ca69ba0b9737cbb469f34c99b9bf201fc3b6d46bc8c1d76b17a4630376552a9df4cb5ea6e66421178ca1a84bf659857ce317e964ec8f84caef35b96488"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 08:49:03.756154) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:49:03.756483) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1487694481.504889-240807790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:49:03.837915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 08:49:03.838268) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 08:49:03.838584) ========= ========= Total master_lag: 0.16 =========