builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-087 starttime: 1487333841.05 results: success (0) revision: 975ce5d8f7d818cc3cd32b134e4010577d3208cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.050876) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.051302) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.051577) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.067537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.067805) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 04:17:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 215M=0s 2017-02-17 04:17:21 (215 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.218172 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.407169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.407508) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015711 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:21.435348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 04:17:21.435678) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 975ce5d8f7d818cc3cd32b134e4010577d3208cd --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 975ce5d8f7d818cc3cd32b134e4010577d3208cd --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=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 04:17:21,499 truncating revision to first 12 chars 2017-02-17 04:17:21,499 Setting DEBUG logging. 2017-02-17 04:17:21,499 attempt 1/10 2017-02-17 04:17:21,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/975ce5d8f7d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 04:17:22,398 unpacking tar archive at: mozilla-central-975ce5d8f7d8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.224078 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 04:17:22.672400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:22.672739) ========= script_repo_revision: 975ce5d8f7d818cc3cd32b134e4010577d3208cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:22.673118) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:22.673392) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 04:17:22.681342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-02-17 04:17:22.681629) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:17:22 INFO - MultiFileLogger online at 20170217 04:17:22 in /builds/slave/test 04:17:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:17:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:17:22 INFO - {'append_to_log': False, 04:17:22 INFO - 'base_work_dir': '/builds/slave/test', 04:17:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:17:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:17:22 INFO - 'branch': 'Firefox-Non-PGO', 04:17:22 INFO - 'buildbot_json_path': 'buildprops.json', 04:17:22 INFO - 'config_files': ('talos/linux_config.py',), 04:17:22 INFO - 'default_actions': ('clobber', 04:17:22 INFO - 'read-buildbot-config', 04:17:22 INFO - 'download-and-extract', 04:17:22 INFO - 'populate-webroot', 04:17:22 INFO - 'create-virtualenv', 04:17:22 INFO - 'install', 04:17:22 INFO - 'run-tests'), 04:17:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:17:22 INFO - 'download_minidump_stackwalk': True, 04:17:22 INFO - 'download_symbols': 'ondemand', 04:17:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:17:22 INFO - 'tooltool.py': '/tools/tooltool.py', 04:17:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:17:22 INFO - '/tools/misc-python/virtualenv.py')}, 04:17:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:17:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:17:22 INFO - 'installer_path': 'installer.exe', 04:17:22 INFO - 'log_level': 'info', 04:17:22 INFO - 'log_name': 'talos', 04:17:22 INFO - 'log_to_console': True, 04:17:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:17:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:17:22 INFO - 'opt_config_files': (), 04:17:22 INFO - 'pip_index': False, 04:17:22 INFO - 'sps_profile': False, 04:17:22 INFO - 'sps_profile_interval': 0, 04:17:22 INFO - 'suite': 'dromaeojs-e10s', 04:17:22 INFO - 'system_bits': '32', 04:17:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:17:22 INFO - 'title': 'talos-linux64-ix-087', 04:17:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:17:22 INFO - 'use_talos_json': True, 04:17:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:17:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:17:22 INFO - 'work_dir': 'build'} 04:17:22 INFO - [mozharness: 2017-02-17 12:17:22.845012Z] Running clobber step. 04:17:22 INFO - Running pre-action listener: _resource_record_pre_action 04:17:22 INFO - Running main action method: clobber 04:17:22 INFO - rmtree: /builds/slave/test/build 04:17:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:23 INFO - Running post-action listener: _resource_record_post_action 04:17:23 INFO - [mozharness: 2017-02-17 12:17:23.840377Z] Finished clobber step (success) 04:17:23 INFO - [mozharness: 2017-02-17 12:17:23.840476Z] Running read-buildbot-config step. 04:17:23 INFO - Running pre-action listener: _resource_record_pre_action 04:17:23 INFO - Running main action method: read_buildbot_config 04:17:23 INFO - Using buildbot properties: 04:17:23 INFO - { 04:17:23 INFO - "project": "", 04:17:23 INFO - "product": "firefox", 04:17:23 INFO - "who": "cbook@mozilla.com", 04:17:23 INFO - "installer_path": "public/build/target.tar.bz2", 04:17:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:17:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:17:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:17:23 INFO - "stage_platform": "linux64", 04:17:23 INFO - "basedir": "/builds/slave/test", 04:17:23 INFO - "buildnumber": 425, 04:17:23 INFO - "platform": "ubuntu64_hw", 04:17:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:17:23 INFO - "slavebuilddir": "test", 04:17:23 INFO - "taskId": "WqtM3R3tQkG-d_NWhC-qVQ", 04:17:23 INFO - "branch": "mozilla-central", 04:17:23 INFO - "script_repo_revision": "production", 04:17:23 INFO - "revision": "975ce5d8f7d818cc3cd32b134e4010577d3208cd", 04:17:23 INFO - "slavename": "talos-linux64-ix-087", 04:17:23 INFO - "repo_path": "mozilla-central" 04:17:23 INFO - } 04:17:23 INFO - Finding installer, test and symbols from parent task. 04:17:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqtM3R3tQkG-d_NWhC-qVQ'}, attempt #1 04:17:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqtM3R3tQkG-d_NWhC-qVQ'}, attempt #1 04:17:24 INFO - Task dependencies: dUd_aWnLQECS5ytsDg5CnQ 04:17:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ'}, attempt #1 04:17:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 04:17:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/test_packages.json 04:17:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.crashreporter-symbols.zip 04:17:25 INFO - Running post-action listener: _resource_record_post_action 04:17:25 INFO - [mozharness: 2017-02-17 12:17:25.245602Z] Finished read-buildbot-config step (success) 04:17:25 INFO - [mozharness: 2017-02-17 12:17:25.245822Z] Running download-and-extract step. 04:17:25 INFO - Running pre-action listener: _resource_record_pre_action 04:17:25 INFO - Running main action method: download_and_extract 04:17:25 INFO - mkdir: /builds/slave/test/build/tests 04:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:25 INFO - trying https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.test_packages.json 04:17:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:17:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:17:25 INFO - Downloaded 896 bytes. 04:17:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:17:25 INFO - Using the following test package requirements: 04:17:25 INFO - {u'common': [u'target.common.tests.zip'], 04:17:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:17:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:17:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:17:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:17:25 INFO - u'mozbase': [u'target.common.tests.zip'], 04:17:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:17:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:17:25 INFO - u'web-platform': [u'target.common.tests.zip', 04:17:25 INFO - u'target.web-platform.tests.zip'], 04:17:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:17:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:17:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip 04:17:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:17:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip into memory 04:17:26 INFO - Expected file size: 57027579 04:17:26 INFO - Obtained file size: 57027579 04:17:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:17:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip 04:17:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:17:36 INFO - Fetch https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.common.tests.zip into memory 04:17:37 INFO - Expected file size: 57027579 04:17:37 INFO - Obtained file size: 57027579 04:17:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.talos.tests.zip 04:17:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:17:48 INFO - Fetch https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.talos.tests.zip into memory 04:17:48 INFO - Expected file size: 14403647 04:17:48 INFO - Obtained file size: 14403647 04:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:49 INFO - trying https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 04:17:49 INFO - Downloading https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:17:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:17:50 INFO - Downloaded 61491183 bytes. 04:17:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 04:17:50 INFO - mkdir: /builds/slave/test/properties 04:17:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:50 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:50 INFO - Contents: 04:17:50 INFO - build_url:https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 04:17:50 INFO - Running post-action listener: _resource_record_post_action 04:17:50 INFO - Running post-action listener: set_extra_try_arguments 04:17:50 INFO - [mozharness: 2017-02-17 12:17:50.406129Z] Finished download-and-extract step (success) 04:17:50 INFO - [mozharness: 2017-02-17 12:17:50.406225Z] Running populate-webroot step. 04:17:50 INFO - Running pre-action listener: _resource_record_pre_action 04:17:50 INFO - Running main action method: populate_webroot 04:17:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:50 INFO - '--tppagecycles', 04:17:50 INFO - '7'], 04:17:50 INFO - 'tests': ['tsvgm']}}, 04:17:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['damp', 'tps']}, 04:17:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'tests': ['damp', 'tps']}, 04:17:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['dromaeo_dom']}, 04:17:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['basic_compositor_video']}, 04:17:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:17:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['a11yr', 04:17:50 INFO - 'ts_paint', 04:17:50 INFO - 'tpaint', 04:17:50 INFO - 'sessionrestore', 04:17:50 INFO - 'sessionrestore_no_auto_restore', 04:17:50 INFO - 'tabpaint']}, 04:17:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['tsvgx', 04:17:50 INFO - 'tsvgr_opacity', 04:17:50 INFO - 'tart', 04:17:50 INFO - 'tscrollx', 04:17:50 INFO - 'cart', 04:17:50 INFO - 'tsvg_static']}, 04:17:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:50 INFO - 'tsvgr_opacity', 04:17:50 INFO - 'tart', 04:17:50 INFO - 'tscrollx', 04:17:50 INFO - 'cart', 04:17:50 INFO - 'tsvg_static']}, 04:17:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'talos_options': ['--disable-e10s'], 04:17:50 INFO - 'tests': ['tp5o']}, 04:17:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:17:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'talos_options': ['--disable-e10s', 04:17:50 INFO - '--xperf_path', 04:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:50 INFO - 'tests': ['tp5n']}, 04:17:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:17:50 INFO - 'talos_options': ['--xperf_path', 04:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:50 INFO - 'tests': ['tp5n']}}, 04:17:50 INFO - 'talos.zip': {'path': '', 04:17:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:50 INFO - Running post-action listener: _resource_record_post_action 04:17:50 INFO - [mozharness: 2017-02-17 12:17:50.419236Z] Finished populate-webroot step (success) 04:17:50 INFO - [mozharness: 2017-02-17 12:17:50.419326Z] Running create-virtualenv step. 04:17:50 INFO - Running pre-action listener: _resource_record_pre_action 04:17:50 INFO - Running main action method: create_virtualenv 04:17:50 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:17:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:50 INFO - Using real prefix '/usr' 04:17:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:51 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:52 INFO - Installing pip.................done. 04:17:52 INFO - Return code: 0 04:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:17:53 INFO - Reading from file tmpfile_stdout 04:17:53 INFO - Output received: 04:17:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:17:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:17:53 INFO - Using env: {'DISPLAY': ':0', 04:17:53 INFO - 'HOME': '/home/cltbld', 04:17:53 INFO - 'LANG': 'en_US.UTF-8', 04:17:53 INFO - 'LANGUAGE': 'en_US:en', 04:17:53 INFO - 'LOGNAME': 'cltbld', 04:17:53 INFO - 'MAIL': '/var/mail/cltbld', 04:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:53 INFO - 'MOZ_NO_REMOTE': '1', 04:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:53 INFO - 'NO_EM_RESTART': '1', 04:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:53 INFO - 'PWD': '/builds/slave/test', 04:17:53 INFO - 'SHELL': '/bin/bash', 04:17:53 INFO - 'SHLVL': '1', 04:17:53 INFO - 'TERM': 'linux', 04:17:53 INFO - 'TMOUT': '86400', 04:17:53 INFO - 'USER': 'cltbld', 04:17:53 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:53 INFO - '_': '/tools/buildbot/bin/python'} 04:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:17:53 INFO - Cleaning up... 04:17:53 INFO - Return code: 0 04:17:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:17:53 INFO - Using env: {'DISPLAY': ':0', 04:17:53 INFO - 'HOME': '/home/cltbld', 04:17:53 INFO - 'LANG': 'en_US.UTF-8', 04:17:53 INFO - 'LANGUAGE': 'en_US:en', 04:17:53 INFO - 'LOGNAME': 'cltbld', 04:17:53 INFO - 'MAIL': '/var/mail/cltbld', 04:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:53 INFO - 'MOZ_NO_REMOTE': '1', 04:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:53 INFO - 'NO_EM_RESTART': '1', 04:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:53 INFO - 'PWD': '/builds/slave/test', 04:17:53 INFO - 'SHELL': '/bin/bash', 04:17:53 INFO - 'SHLVL': '1', 04:17:53 INFO - 'TERM': 'linux', 04:17:53 INFO - 'TMOUT': '86400', 04:17:53 INFO - 'USER': 'cltbld', 04:17:53 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:53 INFO - '_': '/tools/buildbot/bin/python'} 04:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:53 INFO - Downloading/unpacking psutil>=3.1.1 04:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:55 INFO - Installing collected packages: psutil 04:17:55 INFO - Running setup.py install for psutil 04:17:55 INFO - building 'psutil._psutil_linux' extension 04:17:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:17:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:17:56 INFO - building 'psutil._psutil_posix' extension 04:17:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:17:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:17:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:56 INFO - Successfully installed psutil 04:17:56 INFO - Cleaning up... 04:17:56 INFO - Return code: 0 04:17:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:17:56 INFO - Using env: {'DISPLAY': ':0', 04:17:56 INFO - 'HOME': '/home/cltbld', 04:17:56 INFO - 'LANG': 'en_US.UTF-8', 04:17:56 INFO - 'LANGUAGE': 'en_US:en', 04:17:56 INFO - 'LOGNAME': 'cltbld', 04:17:56 INFO - 'MAIL': '/var/mail/cltbld', 04:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:56 INFO - 'MOZ_NO_REMOTE': '1', 04:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:56 INFO - 'NO_EM_RESTART': '1', 04:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:56 INFO - 'PWD': '/builds/slave/test', 04:17:56 INFO - 'SHELL': '/bin/bash', 04:17:56 INFO - 'SHLVL': '1', 04:17:56 INFO - 'TERM': 'linux', 04:17:56 INFO - 'TMOUT': '86400', 04:17:56 INFO - 'USER': 'cltbld', 04:17:56 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:56 INFO - '_': '/tools/buildbot/bin/python'} 04:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:17:58 INFO - Installing collected packages: mozsystemmonitor 04:17:58 INFO - Running setup.py install for mozsystemmonitor 04:17:58 INFO - Successfully installed mozsystemmonitor 04:17:58 INFO - Cleaning up... 04:17:58 INFO - Return code: 0 04:17:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:58 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:17:58 INFO - Using env: {'DISPLAY': ':0', 04:17:58 INFO - 'HOME': '/home/cltbld', 04:17:58 INFO - 'LANG': 'en_US.UTF-8', 04:17:58 INFO - 'LANGUAGE': 'en_US:en', 04:17:58 INFO - 'LOGNAME': 'cltbld', 04:17:58 INFO - 'MAIL': '/var/mail/cltbld', 04:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:58 INFO - 'MOZ_NO_REMOTE': '1', 04:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:58 INFO - 'NO_EM_RESTART': '1', 04:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:58 INFO - 'PWD': '/builds/slave/test', 04:17:58 INFO - 'SHELL': '/bin/bash', 04:17:58 INFO - 'SHLVL': '1', 04:17:58 INFO - 'TERM': 'linux', 04:17:58 INFO - 'TMOUT': '86400', 04:17:58 INFO - 'USER': 'cltbld', 04:17:58 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:58 INFO - '_': '/tools/buildbot/bin/python'} 04:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:58 INFO - Downloading/unpacking jsonschema==2.5.1 04:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:18:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:18:01 INFO - warning: no files found matching '*.txt' 04:18:01 INFO - no previously-included directories found matching 'build' 04:18:01 INFO - no previously-included directories found matching 'dist' 04:18:01 INFO - no previously-included directories found matching '.git*' 04:18:01 INFO - Installing collected packages: jsonschema, functools32 04:18:01 INFO - Running setup.py install for functools32 04:18:01 INFO - warning: no files found matching '*.txt' 04:18:01 INFO - no previously-included directories found matching 'build' 04:18:01 INFO - no previously-included directories found matching 'dist' 04:18:01 INFO - no previously-included directories found matching '.git*' 04:18:01 INFO - Successfully installed jsonschema functools32 04:18:01 INFO - Cleaning up... 04:18:01 INFO - Return code: 0 04:18:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:01 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:18:01 INFO - Using env: {'DISPLAY': ':0', 04:18:01 INFO - 'HOME': '/home/cltbld', 04:18:01 INFO - 'LANG': 'en_US.UTF-8', 04:18:01 INFO - 'LANGUAGE': 'en_US:en', 04:18:01 INFO - 'LOGNAME': 'cltbld', 04:18:01 INFO - 'MAIL': '/var/mail/cltbld', 04:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:01 INFO - 'MOZ_NO_REMOTE': '1', 04:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:01 INFO - 'NO_EM_RESTART': '1', 04:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:01 INFO - 'PWD': '/builds/slave/test', 04:18:01 INFO - 'SHELL': '/bin/bash', 04:18:01 INFO - 'SHLVL': '1', 04:18:01 INFO - 'TERM': 'linux', 04:18:01 INFO - 'TMOUT': '86400', 04:18:01 INFO - 'USER': 'cltbld', 04:18:01 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:01 INFO - '_': '/tools/buildbot/bin/python'} 04:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:18:01 INFO - Cleaning up... 04:18:01 INFO - Return code: 0 04:18:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:01 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:18:01 INFO - Using env: {'DISPLAY': ':0', 04:18:01 INFO - 'HOME': '/home/cltbld', 04:18:01 INFO - 'LANG': 'en_US.UTF-8', 04:18:01 INFO - 'LANGUAGE': 'en_US:en', 04:18:01 INFO - 'LOGNAME': 'cltbld', 04:18:01 INFO - 'MAIL': '/var/mail/cltbld', 04:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:01 INFO - 'MOZ_NO_REMOTE': '1', 04:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:01 INFO - 'NO_EM_RESTART': '1', 04:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:01 INFO - 'PWD': '/builds/slave/test', 04:18:01 INFO - 'SHELL': '/bin/bash', 04:18:01 INFO - 'SHLVL': '1', 04:18:01 INFO - 'TERM': 'linux', 04:18:01 INFO - 'TMOUT': '86400', 04:18:01 INFO - 'USER': 'cltbld', 04:18:01 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:01 INFO - '_': '/tools/buildbot/bin/python'} 04:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:01 INFO - Downloading/unpacking blobuploader==1.2.4 04:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:03 INFO - Downloading blobuploader-1.2.4.tar.gz 04:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:18:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:18:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:04 INFO - Downloading docopt-0.6.1.tar.gz 04:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:18:04 INFO - Installing collected packages: blobuploader, requests, docopt 04:18:04 INFO - Running setup.py install for blobuploader 04:18:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:18:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:18:04 INFO - Running setup.py install for requests 04:18:05 INFO - Running setup.py install for docopt 04:18:05 INFO - Successfully installed blobuploader requests docopt 04:18:05 INFO - Cleaning up... 04:18:05 INFO - Return code: 0 04:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:05 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:18:05 INFO - Using env: {'DISPLAY': ':0', 04:18:05 INFO - 'HOME': '/home/cltbld', 04:18:05 INFO - 'LANG': 'en_US.UTF-8', 04:18:05 INFO - 'LANGUAGE': 'en_US:en', 04:18:05 INFO - 'LOGNAME': 'cltbld', 04:18:05 INFO - 'MAIL': '/var/mail/cltbld', 04:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:05 INFO - 'MOZ_NO_REMOTE': '1', 04:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:05 INFO - 'NO_EM_RESTART': '1', 04:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:05 INFO - 'PWD': '/builds/slave/test', 04:18:05 INFO - 'SHELL': '/bin/bash', 04:18:05 INFO - 'SHLVL': '1', 04:18:05 INFO - 'TERM': 'linux', 04:18:05 INFO - 'TMOUT': '86400', 04:18:05 INFO - 'USER': 'cltbld', 04:18:05 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:05 INFO - '_': '/tools/buildbot/bin/python'} 04:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:05 INFO - Running setup.py (path:/tmp/pip-PvM0pI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:05 INFO - Running setup.py (path:/tmp/pip-UPbh3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:05 INFO - Running setup.py (path:/tmp/pip-48Obg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:05 INFO - Running setup.py (path:/tmp/pip-GG_Vy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:05 INFO - Running setup.py (path:/tmp/pip-bhwL6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:05 INFO - Running setup.py (path:/tmp/pip-5v3kjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:05 INFO - Running setup.py (path:/tmp/pip-wwj_7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:05 INFO - Running setup.py (path:/tmp/pip-8yFWei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:06 INFO - Running setup.py (path:/tmp/pip-I2rCQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:06 INFO - Running setup.py (path:/tmp/pip-aOKvjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:06 INFO - Running setup.py (path:/tmp/pip-8ConwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:06 INFO - Running setup.py (path:/tmp/pip-NMigv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:06 INFO - Running setup.py (path:/tmp/pip-k6NYfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:06 INFO - Running setup.py (path:/tmp/pip-uY1jMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:06 INFO - Running setup.py (path:/tmp/pip-up1vno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:06 INFO - Running setup.py (path:/tmp/pip-j6ZgAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:06 INFO - Running setup.py (path:/tmp/pip-xkE02M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:18:06 INFO - Running setup.py install for manifestparser 04:18:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for mozcrash 04:18:06 INFO - Running setup.py install for mozdebug 04:18:07 INFO - Running setup.py install for mozdevice 04:18:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozfile 04:18:07 INFO - Running setup.py install for mozhttpd 04:18:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozinfo 04:18:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozInstall 04:18:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozleak 04:18:07 INFO - Running setup.py install for mozlog 04:18:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for moznetwork 04:18:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozprocess 04:18:07 INFO - Running setup.py install for mozprofile 04:18:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:18:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:18:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:18:08 INFO - Running setup.py install for mozrunner 04:18:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:18:08 INFO - Running setup.py install for mozscreenshot 04:18:08 INFO - Running setup.py install for moztest 04:18:08 INFO - Running setup.py install for mozversion 04:18:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:18:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:18:08 INFO - Cleaning up... 04:18:08 INFO - Return code: 0 04:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:08 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:18:08 INFO - Using env: {'DISPLAY': ':0', 04:18:08 INFO - 'HOME': '/home/cltbld', 04:18:08 INFO - 'LANG': 'en_US.UTF-8', 04:18:08 INFO - 'LANGUAGE': 'en_US:en', 04:18:08 INFO - 'LOGNAME': 'cltbld', 04:18:08 INFO - 'MAIL': '/var/mail/cltbld', 04:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:08 INFO - 'MOZ_NO_REMOTE': '1', 04:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:08 INFO - 'NO_EM_RESTART': '1', 04:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:08 INFO - 'PWD': '/builds/slave/test', 04:18:08 INFO - 'SHELL': '/bin/bash', 04:18:08 INFO - 'SHLVL': '1', 04:18:08 INFO - 'TERM': 'linux', 04:18:08 INFO - 'TMOUT': '86400', 04:18:08 INFO - 'USER': 'cltbld', 04:18:08 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:08 INFO - '_': '/tools/buildbot/bin/python'} 04:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:08 INFO - Running setup.py (path:/tmp/pip-X5gblM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:08 INFO - Running setup.py (path:/tmp/pip-A8SlEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:08 INFO - Running setup.py (path:/tmp/pip-71Uk3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:09 INFO - Running setup.py (path:/tmp/pip-bzSIDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:09 INFO - Running setup.py (path:/tmp/pip-Ckt3C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:09 INFO - Running setup.py (path:/tmp/pip-OMWogu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:09 INFO - Running setup.py (path:/tmp/pip-OrFYxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:09 INFO - Running setup.py (path:/tmp/pip-IrUT_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:09 INFO - Running setup.py (path:/tmp/pip-08YJPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:09 INFO - Running setup.py (path:/tmp/pip-YaWEvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:09 INFO - Running setup.py (path:/tmp/pip-MfIHP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:09 INFO - Running setup.py (path:/tmp/pip-Fjp6tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:09 INFO - Running setup.py (path:/tmp/pip-2IWbzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:09 INFO - Running setup.py (path:/tmp/pip-aNz2bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:09 INFO - Running setup.py (path:/tmp/pip-QMk94b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:09 INFO - Running setup.py (path:/tmp/pip-ZrOB6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:09 INFO - Running setup.py (path:/tmp/pip-XJ61va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:18:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:12 INFO - Downloading blessings-1.6.tar.gz 04:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:18:12 INFO - Installing collected packages: blessings 04:18:12 INFO - Running setup.py install for blessings 04:18:12 INFO - Successfully installed blessings 04:18:12 INFO - Cleaning up... 04:18:12 INFO - Return code: 0 04:18:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:12 INFO - Reading from file tmpfile_stdout 04:18:12 INFO - Current package versions: 04:18:12 INFO - argparse == 1.2.1 04:18:12 INFO - blessings == 1.6 04:18:12 INFO - blobuploader == 1.2.4 04:18:12 INFO - docopt == 0.6.1 04:18:12 INFO - functools32 == 3.2.3-2 04:18:12 INFO - jsonschema == 2.5.1 04:18:12 INFO - manifestparser == 1.1 04:18:12 INFO - mozInstall == 1.12 04:18:12 INFO - mozcrash == 1.0 04:18:12 INFO - mozdebug == 0.1 04:18:12 INFO - mozdevice == 0.49 04:18:12 INFO - mozfile == 1.2 04:18:12 INFO - mozhttpd == 0.7 04:18:12 INFO - mozinfo == 0.9 04:18:12 INFO - mozleak == 0.1 04:18:12 INFO - mozlog == 3.4 04:18:12 INFO - moznetwork == 0.27 04:18:12 INFO - mozprocess == 0.24 04:18:12 INFO - mozprofile == 0.28 04:18:12 INFO - mozrunner == 6.13 04:18:12 INFO - mozscreenshot == 0.1 04:18:12 INFO - mozsystemmonitor == 0.3 04:18:12 INFO - moztest == 0.8 04:18:12 INFO - mozversion == 1.4 04:18:12 INFO - psutil == 3.1.1 04:18:12 INFO - requests == 1.2.3 04:18:12 INFO - wsgiref == 0.1.2 04:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:12 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:18:12 INFO - Using env: {'DISPLAY': ':0', 04:18:12 INFO - 'HOME': '/home/cltbld', 04:18:12 INFO - 'LANG': 'en_US.UTF-8', 04:18:12 INFO - 'LANGUAGE': 'en_US:en', 04:18:12 INFO - 'LOGNAME': 'cltbld', 04:18:12 INFO - 'MAIL': '/var/mail/cltbld', 04:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:12 INFO - 'MOZ_NO_REMOTE': '1', 04:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:12 INFO - 'NO_EM_RESTART': '1', 04:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:12 INFO - 'PWD': '/builds/slave/test', 04:18:12 INFO - 'SHELL': '/bin/bash', 04:18:12 INFO - 'SHLVL': '1', 04:18:12 INFO - 'TERM': 'linux', 04:18:12 INFO - 'TMOUT': '86400', 04:18:12 INFO - 'USER': 'cltbld', 04:18:12 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:12 INFO - '_': '/tools/buildbot/bin/python'} 04:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:18:12 INFO - Cleaning up... 04:18:12 INFO - Return code: 0 04:18:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:12 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 0x116f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1267a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x140cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:18:12 INFO - Using env: {'DISPLAY': ':0', 04:18:12 INFO - 'HOME': '/home/cltbld', 04:18:12 INFO - 'LANG': 'en_US.UTF-8', 04:18:12 INFO - 'LANGUAGE': 'en_US:en', 04:18:12 INFO - 'LOGNAME': 'cltbld', 04:18:12 INFO - 'MAIL': '/var/mail/cltbld', 04:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:12 INFO - 'MOZ_NO_REMOTE': '1', 04:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:12 INFO - 'NO_EM_RESTART': '1', 04:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:12 INFO - 'PWD': '/builds/slave/test', 04:18:12 INFO - 'SHELL': '/bin/bash', 04:18:12 INFO - 'SHLVL': '1', 04:18:12 INFO - 'TERM': 'linux', 04:18:12 INFO - 'TMOUT': '86400', 04:18:12 INFO - 'USER': 'cltbld', 04:18:12 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:12 INFO - '_': '/tools/buildbot/bin/python'} 04:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:18:12 INFO - Cleaning up... 04:18:13 INFO - Return code: 0 04:18:13 INFO - Running post-action listener: _resource_record_post_action 04:18:13 INFO - Running post-action listener: _start_resource_monitoring 04:18:13 INFO - Starting resource monitoring. 04:18:13 INFO - [mozharness: 2017-02-17 12:18:13.038489Z] Finished create-virtualenv step (success) 04:18:13 INFO - [mozharness: 2017-02-17 12:18:13.039000Z] Running install step. 04:18:13 INFO - Running pre-action listener: _resource_record_pre_action 04:18:13 INFO - Running main action method: install 04:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:13 INFO - Reading from file tmpfile_stdout 04:18:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:18:13 INFO - Reading from file tmpfile_stdout 04:18:13 INFO - Output received: 04:18:13 INFO - Usage: mozinstall [options] installer 04:18:13 INFO - Options: 04:18:13 INFO - -h, --help show this help message and exit 04:18:13 INFO - -d DEST, --destination=DEST 04:18:13 INFO - Directory to install application into. [default: 04:18:13 INFO - "/builds/slave/test"] 04:18:13 INFO - --app=APP Application being installed. [default: firefox] 04:18:13 INFO - mkdir: /builds/slave/test/build/application 04:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:18:28 INFO - Reading from file tmpfile_stdout 04:18:28 INFO - Output received: 04:18:28 INFO - /builds/slave/test/build/application/firefox/firefox 04:18:28 INFO - Running post-action listener: _resource_record_post_action 04:18:28 INFO - [mozharness: 2017-02-17 12:18:28.743665Z] Finished install step (success) 04:18:28 INFO - [mozharness: 2017-02-17 12:18:28.743935Z] Running run-tests step. 04:18:28 INFO - Running pre-action listener: _resource_record_pre_action 04:18:28 INFO - Running main action method: run_tests 04:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:28 INFO - Python 2.7.3 04:18:28 INFO - Return code: 0 04:18:28 INFO - grabbing minidump binary from tooltool 04:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:28 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 0x140a290>, '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 0x140ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140b040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:18:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:18:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:18:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:28 INFO - Return code: 0 04:18:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:18:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:28 INFO - ENV: RUST_BACKTRACE is now 1 04:18:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:18:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:18:28 INFO - Using env: {'DISPLAY': ':0', 04:18:28 INFO - 'HOME': '/home/cltbld', 04:18:28 INFO - 'LANG': 'en_US.UTF-8', 04:18:28 INFO - 'LANGUAGE': 'en_US:en', 04:18:28 INFO - 'LOGNAME': 'cltbld', 04:18:28 INFO - 'MAIL': '/var/mail/cltbld', 04:18:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:28 INFO - 'MOZ_NO_REMOTE': '1', 04:18:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:28 INFO - 'NO_EM_RESTART': '1', 04:18:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:28 INFO - 'PWD': '/builds/slave/test', 04:18:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:28 INFO - 'RUST_BACKTRACE': '1', 04:18:28 INFO - 'SHELL': '/bin/bash', 04:18:28 INFO - 'SHLVL': '1', 04:18:28 INFO - 'TERM': 'linux', 04:18:28 INFO - 'TMOUT': '86400', 04:18:28 INFO - 'USER': 'cltbld', 04:18:28 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017', 04:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:28 INFO - '_': '/tools/buildbot/bin/python'} 04:18:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:18:29 INFO - mozversion application_buildid: 20170217114148 04:18:29 INFO - mozversion application_changeset: 975ce5d8f7d818cc3cd32b134e4010577d3208cd 04:18:29 INFO - mozversion application_display_name: Nightly 04:18:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:29 INFO - mozversion application_name: Firefox 04:18:29 INFO - mozversion application_remotingname: firefox 04:18:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:18:29 INFO - mozversion application_vendor: Mozilla 04:18:29 INFO - mozversion application_version: 54.0a1 04:18:29 INFO - mozversion platform_buildid: 20170217114148 04:18:29 INFO - mozversion platform_changeset: 975ce5d8f7d818cc3cd32b134e4010577d3208cd 04:18:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:18:29 INFO - mozversion platform_version: 54.0a1 04:18:29 INFO - using testdate: 1487333909 04:18:29 INFO - actual date: 1487333909 04:18:29 INFO - starting webserver on 'localhost:42837' 04:18:29 INFO - SUITE-START | Running 2 tests 04:18:29 INFO - TEST-START | dromaeo_css 04:18:29 INFO - operating with platform_type : linux_ 04:18:29 INFO - Initialising browser for dromaeo_css test... 04:18:29 INFO - TEST-INFO | started process 22218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptHpIQA/profile http://localhost:42837/getInfo.html) 04:18:32 INFO - PROCESS | 22218 | Iterating over profile /tmp/tmptHpIQA/profile 04:18:32 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:32 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/extensions.json = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/user.js = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/localstore.rdf = Fri Feb 17 2017 04:17:49 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/places.sqlite-wal = Fri Feb 17 2017 04:18:33 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | __metrics Screen width/height:1600/1200 04:18:33 INFO - PROCESS | 22218 | colorDepth:24 04:18:33 INFO - PROCESS | 22218 | Browser inner width/height: 1024/697 04:18:33 INFO - PROCESS | 22218 | __metrics 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/blocklist.xml = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/cert8.db = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | Stat failure 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/extensions = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/places.sqlite = Fri Feb 17 2017 04:18:32 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/gmp = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/addons.json = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/prefs.js = Fri Feb 17 2017 04:17:49 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/places.sqlite-shm = Fri Feb 17 2017 04:18:30 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/crashes = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/bookmarkbackups = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/secmod.db = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/compatibility.ini = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/cache2 = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/thumbnails = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/pluginreg.dat = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/safebrowsing = Fri Feb 17 2017 04:18:33 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/.parentlock = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/sessionCheckpoints.json = Fri Feb 17 2017 04:18:32 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/minidumps = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/content-prefs.sqlite = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/key3.db = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/permissions.sqlite = Fri Feb 17 2017 04:18:30 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/startupCache = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/frequencyCap.json = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/extensions.ini = Fri Feb 17 2017 04:18:29 GMT-0800 (PST) 04:18:33 INFO - PROCESS | 22218 | No birth date. Using mtime. 04:18:33 INFO - PROCESS | 22218 | Using date: /tmp/tmptHpIQA/profile/directoryLinks.json = Fri Feb 17 2017 04:18:31 GMT-0800 (PST) 04:18:34 ERROR - PROCESS | 22218 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:18:35 INFO - TEST-INFO | 22218: exit 0 04:18:35 INFO - Browser initialized. 04:18:35 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:35 INFO - TEST-INFO | started process 22347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptHpIQA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:47 INFO - PROCESS | 22347 | RSS: Main: 153059328 04:18:47 INFO - PROCESS | 22347 | 04:21:52 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42837/tests/dromaeo/cssquery-ext.html) 04:21:54 INFO - PROCESS | 22347 | RSS: Main: 165064704 04:21:54 INFO - PROCESS | 22347 | 04:23:35 INFO - PROCESS | 22347 | ************************* 04:23:35 INFO - PROCESS | 22347 | A coding exception was thrown and uncaught in a Task. 04:23:35 INFO - PROCESS | 22347 | 04:23:35 ERROR - PROCESS | 22347 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:23:35 INFO - PROCESS | 22347 | Full stack: 04:23:35 INFO - PROCESS | 22347 | ************************* 04:24:56 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-ext.html (next: http://localhost:42837/tests/dromaeo/cssquery-jquery.html) 04:24:57 INFO - PROCESS | 22347 | RSS: Main: 158240768 04:24:57 INFO - PROCESS | 22347 | 04:27:59 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42837/tests/dromaeo/cssquery-mootools.html) 04:27:59 INFO - PROCESS | 22347 | RSS: Main: 166653952 04:27:59 INFO - PROCESS | 22347 | 04:31:01 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42837/tests/dromaeo/cssquery-prototype.html) 04:31:02 INFO - PROCESS | 22347 | RSS: Main: 158248960 04:31:02 INFO - PROCESS | 22347 | 04:34:05 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42837/tests/dromaeo/cssquery-yui.html) 04:34:06 INFO - PROCESS | 22347 | RSS: Main: 161677312 04:34:06 INFO - PROCESS | 22347 | 04:37:08 INFO - PROCESS | 22347 | Cycle 1(1): loaded http://localhost:42837/tests/dromaeo/cssquery-yui.html (next: http://localhost:42837/tests/dromaeo/cssquery-dojo.html) 04:37:09 INFO - PROCESS | 22347 | RSS: Main: 163459072 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | __start_tp_report 04:37:09 INFO - PROCESS | 22347 | _x_x_mozilla_page_load 04:37:09 INFO - PROCESS | 22347 | _x_x_mozilla_page_load_details 04:37:09 INFO - PROCESS | 22347 | |i|pagename|runs| 04:37:09 INFO - PROCESS | 22347 | |0;dojo.html;2595.5198288316665;2620.3580122869894;2632.749888760567;2636.1037247335903;2663.5338815707246;4731.006488637382;4885.096257192422;4893.241547560129;4912.852614421568;4923.753812309388;4757.1437141314545;4761.262004389317;4835.564799168074;4863.951360486394;4866.756662166895;4333.544977777336;4380.386745855567;4440.622547083502;4464.263396539567;4472.955270447303;4128.422020917074;4532.5694059064335;4552.954470455303;4566.817327306904;4606.815727370901;4277.786110694484;4289.335153051282;4494.280915053609;4525.796339164745;4545.749983750892;4364.301711726141;4414.249577571821;4431.8448854289945;4492.573205545468;4496.303073023687;4374.125174964988;4387.824487020515;4407.294832826765;4408.008198155421;4447.999200179966;4772.522747725235;4822.373091498082;4826.155422800995;4834.057358815033;4873.780679869415;4986.850394488171;5043.067032598981;5180.248863914749;5221.556167725748;5245.31810864589;5296.232046353296;5298.152295632719;5312.096943519611;5314.415414304423;5351.411344752074;4926.211806111005;4935.481774413706;4945.703257804471;4956.90086198274;5002.774875130628;5085.771140298695;5133.230015497706;5316.840494785162;5352.2506849041165;5390.865228369322;5153.0466863630345;5168.586513078944;5169.327987361619;5209.557187639093;5222.869428264324;4961.6526843121355;4984.22744474607;5080.898382032413;5086.059079070384;5235.73821308933;5091.159958606789;5136.332276804066;5169.353830771153;5169.81905633301;5173.844784656466;4888.169011268093;4952.90094198114;5119.948800512022;5133.717645529532;5145.9742701287005;4290.678199135077;4328.588784065509;4331.4152589400655;4337.783110844445;4342.5874541918465;3764.4917936078828;3815.42768584709;3816.866409675648;3819.52255968004;3822.9426558601645;4414.889627759268;4488.4165058542185;4516.841910533116;4528.298113792367;4561.566651168134;5228.790848366023;5259.342582177228;5267.894642107213;5272;5282.418933917266;4522.796474158671;4527.366168736379;4537.705049171794;4576.130535198302;4584.518625544337;4490.057088011489;4531.048479819209;4612.285095810154;4629.305604159403;4636.374089497875;4820.373351464287;4822.903541929071;4827.179379505493;4851.660383773102;4865.489123642037;5069.264956581349;5171.353580802249;5173.81891633791;5178.71517066565;5189.325387699575;4744.715317080986;4786.808527658994;4825.758712064453;4858.611311095033;4889.535494128052;5020.3724534433195;5064.848054558369;5119.232115182753;5124.7950081996305;5158.329417175743;5172.508611681884;5184.688918664892;5197.896042079213;5201.193814958688;5262.315898933114;4774.164521208844;4804.471508134033;5020.899582008266;5051.343325367826;5053.620978426559;5005.374328208974;5035.723035233097;5043.092243396077;5062.949370506395;5135.101357262539;4723.976380118077;4956.925646115239;4985.501449855131;5014.623903207347;5056.974715126401;4846.151923413459;5009.9248511271635;5065.164247899055;5065.721385323843;5142.485751424828;4756.072565849626;4803.543663351976;4916.3362946001835;4962.652614317108;4968.27959945813 04:37:09 INFO - PROCESS | 22347 | |1;ext.html;510.79823469729433;536.3322663284212;537.1352123081838;557.0863783395231;562.1988666150734;38051.61948380515;38998.41502377463;39276.80361598195;42681;43058.56941430585;39950.40074398887;40189.799051004775;40528;40641;40642.79678601603;7664.19525949777;7827;7869;9143.588538515749;9486.478243696629;9337.346385753;9380.390274632162;9420.952895235549;9425.905740942606;9435.528223588793;9446.480443575601;9513.71458856232;9529.285303602222;9567;9568.617255309779;9399.201067909236;9441.716748497556;9466.810663786686;9469.431834089977;9473.715788526355;9251.907480925172;9258.861117083248;9273.814523709556;9286.489243091628;9334.906650933472;33078.04595172868;33164.33671326584;33199.170020749436;33208.3358332832;33262;3337.1657085728566;3430.9656903430896;3441.6730410611203;3467.289205712823;3507.8070706111153;25074.48659241336;25145.748542514706;25275.62086568703;25398.36504087394;25684;3356.3790698720813;3478.7043101336417;3502.6847583717586;3644.69020133289;3645;224710;225213.87393063094;225268;225968;226450;39086;40061.79969100136;45825.77087114543;46230.306545401174;46765;45746.31380529228;49641;49915;50078.49921500811;50119.49880501221;12319.384030798425;12446.128770986124;12505.812412813815;12515.311657858905;12517.56188533397;9157.358984871129;9240.399374040666;9242.353035287466;9256.72229833106;9275.953620231856;15606.21968901573;15624.92187539055;15705.450309239122;15743.370265189493;15789.921050394903;6469.482441404676;6505.154329937078;6583.868322633616;6602.8349291268105;6632;754.2080815144077;775.8099265679945;778.1867947994359;796.6733639208013;797.1709422200962;25159;26486.47027059448;26633.33416664599;26697.33256668599;26976.32559186036;893.9642414303355;911.2633739551163;922.4004397141912;928.8328100941895;1085.4301491716913;14102.224377659122;14203.573892783234;14217.32076560926;14257.714845703027;14326.785098223534;322761;324037;326133;326819;327155;47494;48063.519364806605;48341.516584834404;48392.27411588829;48420.27369589459;1192.3322939154102;1209.3106929050532;1215.2769102384068;1216.6654170103293;1219.945102470391;22935.65596516087;23249.18627848017;23558.293251201805;23815.880920595286;23863.761362386154;41890;42412;42683;42721.78639106909;42728.35907461328;295996;296212;296576;298869;299189;25231.62152567676;36018.55925763045;38226.808865956606;38719.80640096781;42245.577544224165;37839.24321513609;38042.61957380391;39561.406578900765;41365;41787;35213.29573408569;36588.634113658525;36778.63221367752;36955.81522092372;37789.43315850209;35140.64859351374;35518.46722299219;35633.28733425265;35749.821250893576;35945.46081808827;34216.65783342135;34837.65162348445;35025;35840.82079589585;36237.63762362448;30142.54786178253;32693.509597355584;32835.34329313448;32947.50578741273;32968.34063318768;24753.53450516965;25200;25375.873120634278;25618;25718.228453146436 04:37:09 INFO - PROCESS | 22347 | |2;jquery.html;388.3133374844944;388.78811047978866;393.4609584868729;394.95063117110357;415.6612360925847;12227.449764760584;14988.850111498881;16469.917650411746;17941.551461213476;17953.910230448844;16975.49073527793;18056.90971545144;18177.54556136094;18313.17590708418;18426.44720658379;5082.618803589727;5140.2546630738525;5206.843794686166;5257.658252213594;5357.464253574651;4330.696851220416;4331.826726930919;4352.804123814436;4457.442819647544;5172.793088276464;5466.070767969435;5466.453354664542;5468.28912241408;5476.17857321403;5481.369642491125;5281.419043905167;5413.918791218135;5442.83671489854;5446.782128714846;5447.155690867922;5313.468653134694;5324.068288049614;5331;5335.653182543122;5350.866228344284;12056;12134.696632584168;12300.077494187883;12936.547220847226;20828.375148745563;2092.47317182482;2116.502621883856;2158.115172779153;2196.901139448729;2235.564065007324;9313.813723725487;9369.672061477815;9378.296627752878;9875.40747555149;9926.305158638897;1645.6626391589696;1718.9140542972802;1729.6281299520663;1730.3078768492578;1757.8154293799096;62693.68653156705;75727;75998;76307.61846190788;77954.22045779527;9920.355176913477;10353.912839151932;10678.437175078081;10689.198310126772;10991.725206869893;12044.939775301067;15256.466023689118;15296;15317;15330.080195188324;6382.904256436157;6403.231612206472;6671.966640166866;6710.328967103251;6728.596284222962;6039.456448919618;6128.1114238435175;6197.326721785183;6244.906326405107;6282.465990390777;3975;3987.169109931923;3988.102676897675;4016.5180178378782;4089.9386509202386;1951.3267922566693;1962.2837664252731;2000.8699434536709;2001.2295266322571;2022.8583999120215;793.9126696063429;812.7968007998;821.6343727041528;831.2477208126655;848.6266042941086;15516.612084697746;16238.837611623969;16302.755458668129;16656.33374665024;17297.91351043262;799.5122974985254;805.1907832628289;815.4454970620035;816.3877092180865;826.4338927834452;8914.242289405343;8960.283177345797;9441.858372124423;9447.385919915183;9544.809103818023;448941;449855;453358;467803;470716;62181.37818621846;63112.36887631065;72588;85581.57209213915;86965.56517217374;911.7811725185908;913.6071489259682;940.5062342270226;940.6331530703031;950.8003319303024;14462.85537144615;14750.63123421923;14788.299345574966;15069.773953390928;15462.072275663497;36851.447228292134;37169.628303716614;37203.255934881694;37446.43830342602;37516.81241593884;46297.3055404176;47698.28452573284;48676.26985595148;52760.472395277095;53178.4682153189;7547.358474529727;7688.730894418669;7694.961525192338;7745.109312429143;7996.160403157584;11421.600243991752;11732.237404568676;12227.14409991292;12508.562200322946;12592.685182870502;9535.52322383892;9555.378900371454;9575.904240957501;10212;10232.897671023195;9878.065147907613;9898.406095634286;9999.800004000024;10281.280310378203;10496.107830834158;8924.152205540464;9503.66737164196;9544.761380965532;9547.093026162504;9918.355306905029;7380.483366164532;7523.699052037857;7824.491408058458;8004.679812807422;8135.511869287861;8615.91384086151;9489.288303377136;9550.570224340061;9731.562079706571;9966.800663986534 04:37:09 INFO - PROCESS | 22347 | |3;mootools.html;1320.1880843281385;1343.5297645823962;1361.836579610447;1366.0574203799374;1367.1728604194461;7015.333543313377;7070.363667269945;7126.3942564882045;7481.663325150368;7490.700371985114;7328.743493977712;7446.367058799995;7448.106227252723;7449.478536502447;7471.215522370155;2106.7893210678926;2120.151939224307;2137.593857167141;2141.518158414352;2144.185209620342;2079.3034333498294;2123.851330406872;2126;2128.095559387262;2138.721966144407;2050.4361300642295;2052.9897350513224;2067.286786058815;2075.4811297175706;2080.916763329465;2055.2087446333194;2065.845061620377;2070.8032736889973;2072.8549001569822;2074.1288658763356;1741.077229068583;1799.9280028798833;2039.1129858511447;2077.522169900916;2114.0486780948636;7242.275772422769;7405.370543503808;7409.814754631124;7409.925900741031;7421.703131874719;4404.669649776248;4525.117602067598;4530.841420550265;4531.909361812778;4537.387452693877;2698.973010269892;2849.9002534911388;2907.360380716239;2909.3163106669917;2922.8976985805607;1176.5176277726177;1205.5961252980262;1655.5943793770482;1696.6012986948058;1844.1793401936143;9846;9856.852147217798;9893.109620134222;9931.90068099317;9957.701268961942;3934.1934903344745;4012.0371110933165;4030.556638769733;4045.93931091028;4050.6556942660204;7974.401919856034;7989.840203195903;7993.72021979228;7998.960005199937;8054.114047454776;4442.466903971479;4444.444444444444;4460.50934397216;4504.436945381827;4508.864734057984;4030.5163380393956;4104.158647477319;4173.144505376391;4173.770442625685;4188.706790524634;3215.1640573450604;3228.935421291561;3296.423125952949;3375.240570871534;3381.2223188666744;973.2360097323567;992.2805965674828;997.7755005123789;1004.3772860826189;1014.0974532665933;757.1633345153637;772.2702046566053;780.4199970213023;785.9331956783737;787.4055088408215;4767.4994125617;4770.069836381942;4898.804047838046;4910.288008238786;5054.772535235923;520.0612893726784;524.0357741755189;526.1975487381696;539.3743257820909;539.6222644149033;3068.7698422617946;3089.675584063641;3136.6079240094987;3168.350488149924;3199.696028877253;8443.155684431507;8856.158664926821;8921.955390223136;8951.552422378854;8963.327750418614;8668.479891206547;8747.475151490931;8867.246284065797;8877.644894204159;8898.911010889939;6778.118844550276;6863.38229559342;6868;6873.1064961554675;6898.758543450955;4661.976690116528;4665.906681866411;4668.85993420198;4694.694844835075;4766.976165119152;7705.614719263902;7869.5278283300895;7896.802579935547;7915.683372665029;8109.878351824846;7749.302562769378;7858.135605083322;8045.758627241192;8211.753647390588;8255.545944973084;6396.232452105591;6475.222973243241;6493.448056915215;6592.406683398517;6601.834954126185;6980.755673551402;7004.544704594185;7022.964885175541;7023.754168604074;7046.894296585453;6308.589941653778;6365.9681701593045;6408.519361047846;6417.647029413427;6553.868922621426;6336.968315158395;6393.456556192776;6450.161479007699;6569.9014514783275;6607.339266073354;5485.396606373375;5568.888622227451;5582.637128586629;5584.246126772996;5616.494515493625;4229.365595160751;4288.978555107205;4343.3267843483045;4374.978125109354;4424.274532932095;4233.78831058452;4269.082147338338;4296.2911119664905;4317.050248945227;4357.433533640733 04:37:09 INFO - PROCESS | 22347 | |4;prototype.html;358.8833629070553;364.14244454310113;368.1459015085004;375.93233218020765;377.7752237418735;4894.8286809961655;4899.853004409864;4930.433000204977;4943.950560494394;5040.924386134211;4883.584895283905;4961.477351080728;4967.565892712572;5006.499350064992;5007.047251571881;3886.2227554489073;3978.428520734308;4005.6795456363566;4020.43713880057;4040.3131467650496;3987.584407535312;3990.361542153256;3994.221126880245;4010.6390424861756;4029.415734718464;3988.760674359548;3991.900202494932;3995.8002099895034;4009.7594144351287;4021.8391264349393;3859.3246181918335;3945.506811648544;3995.9001024974464;4013.8795836124964;4014.257362387953;3552.3960926642535;3565.8751943682105;3577.656634904142;3580.8030558319283;3604.333198358312;4820.349252850855;4897.461279259279;4912.69103521348;4919.163742163841;4924.827631032933;4574.931376029363;4600.608948239404;4603.056373443436;4616.76916154191;4641.373414589021;5008.323876276692;5192.299039629639;5202.843914682566;5213.4525874782985;5219.817306394296;3976.5029371328583;3980.960190398088;3986.980065099656;3996.8201430935387;3996.840126394941;5229.555487783543;5341.305630268041;5513.2005859150195;5519.972400137974;5521.834344969658;3927.842886284574;3992.4210989406374;4010.232939271893;4018.2365350583295;4020.4773379460485;4090.284200264942;4091.7954102294766;4108.732932359447;4117.382392641128;4134.297169481196;4535.705179163344;4578.2903649934315;4585.403897493305;4588.485799686096;4592.67851250416;4980.875478113004;5236.895262094813;5277.445868183862;5306.230596563554;5355.30381050461;1398.9720205595831;1402.5301523989374;1405.5994041698068;1413.462884104034;1420.6164335629528;973.8393165127816;1015.28422462164;1022.7443139215196;1025.8922813104666;1046.1526163807341;4418.403515525363;4446.199684056836;4456.509783923766;4456.955430445654;4464.419625448671;5156.561692256125;5178.300929374562;5180.533751962342;5245.26566280721;5272.235525848807;4281.828726850953;4445.911081778347;4446.954400975587;4463.620592249695;4470.2177119004045;2751.257160566636;2775.2368098773;2779.2357101797165;2785.958210626842;2797.552391617372;4887.338304976325;4897.510248975145;4912.754362281871;4923.90152196954;4930.8767280818265;5062.924056139161;5348.197770334481;5373.758180881791;5395.811146609928;5432.674039557639;5001.474845141208;5026.472220416877;5037.622178336639;5040.018385475433;5051.671641343374;5386.253010964358;5409.83770486886;5431.402545719889;5447.346318441812;5452.918206226989;5153.278541004337;5336.3863155736035;5338.9589030140305;5342.252084708066;5361.169018802092;4818.903621927471;4850.223964165716;4981.2030075187795;5068.516426950841;5072.923906141485;3742.401215805458;3792.577783331251;3796.335641262823;3802.258559580855;3811.6569508744346;5355.571554275571;5371.248025276543;5376.354837419534;5383.039328369303;5386.272853164929;4984.675996060245;5185.637005409585;5226.529612334908;5230.163173892159;5251.396089449762;5176.9741151294;5199.870003249797;5204.635675502678;5221.295125158055;5226.947730522646;5085.186370180753;5101.897962040813;5102.642815003062;5124.538791508782;5157.742112894265;4940.838902857898;5223.4515375884985;5276.311580294422;5276.815311464234;5314.12316967696;5117.948820511896;5135.2553879687985;5140.280360749423;5157.226416037624;5159.896802064013 04:37:09 INFO - PROCESS | 22347 | |5;yui.html;651.2380514797692;680.6970897950414;685.5509641185024;694.8575542013889;741.0736579275905;12570.748585028294;13064.150830196031;13297.933510332436;13880.652983675413;13905.304734763273;11504.769904601924;11918.821217681741;11949.58176463824;11971.102167337442;12072.215306005104;608.8660494691183;616.214326733414;799.0371602219341;806.5725165662209;811.8254575266317;813.633864760857;830.6386721776016;830.900291964965;831.0692024932084;832.8209434971479;855.0252711908429;855.84166929118;856.2336708645759;857.7298151082421;860.1613426908746;851.7104184577212;855.0722466124263;856.867185586232;857.9099194584572;858.1632907914765;616.9198004259463;620.3982137326785;621.139721485741;623.7193263031654;623.8222106699569;13225.537106201333;13326.333683315892;13330.400131994083;13461.259630720306;14519.927400363036;13233.404496797668;13716.862831371658;13942.163470191821;13985.510507132303;14189.36147873348;10828.945855270753;10834.458277086114;10875.293105948089;10927.617533386372;11055.557777688882;3196.8081915084936;3216.163797412666;3217.8230197339135;3238.6923242291946;3241.9351612967844;59635.403645963415;59842.40157598456;60300;60377.09434358532;60669;10350.378977261389;10364.119049880694;10646.73383165427;10684.51919663617;10967.670969870916;20344.422504936272;20352.28766993148;20414.591708165754;20524.38426847197;20677.27629532959;6635.369639884203;6665.766698165541;6752.3922846944015;6781.25406205317;6793.762218322335;3503.2468019375956;3512.9121771955906;3520.859165633346;3603.387424137903;3607.4228123500116;2961.585378047075;2988.3873805869744;3028.4094601552924;3052.862621182008;3063.5098384258413;473.8720545452708;474.3193517302682;481.9759012049454;482.1971417589674;488.028822642938;636.5098873867097;641.7785863876956;653.633964979613;664.2394458345168;667.5727534378002;8193.426460147852;8260.793480163044;8444.662213511514;8475.70335038283;8630;559.1808001278147;581.9301683797971;585.1281590430228;590.4626789621424;591.1546488521456;4115.97942010288;4259.574042595777;4287.6998610097;4309.61213490781;4380.956190438119;57460.42539574551;57578;59162.40837591569;61679.38320616737;61939.380606193365;45990;49998.00003999972;53571;56308.71845640828;57519.71240143856;49223.507764921895;49454;49704.75147624239;49754.751226243636;50066.74966625144;8049.356051515982;8235.794105147179;8298.668053277801;8430.704925327584;8459.83080338402;33573.83213083919;33683;33768.324633507684;35533.64466355407;36154.10767138675;39625.603743963344;42556.36165457459;43085;44207;44904.32643510285;10142.137918276734;10307.94846025795;10314.638987635397;10315.228798289234;10412.843807342744;11311.151663625096;11410.543578256777;11467.197296189186;11711.414429278673;12163.087768417226;10037.397756134655;10072.647457338959;10153.492325383553;10222.53998570081;10621.097206737204;9713;9832.55753491109;9890.752731181778;9894.406335619886;10150.970524802242;9537.618495260112;9607.51962401891;9866.654667086617;9893.208543316661;10273.845892311472;8539.487630742175;8891.110888911317;8912.955435223043;8917.777055573662;9187.77030574241;7380.852382952418;7593.240675932362;7775.883361749465;8239.752807415787;8361.997094518083 04:37:09 INFO - PROCESS | 22347 | __end_tp_report 04:37:09 INFO - PROCESS | 22347 | __start_cc_report 04:37:09 INFO - PROCESS | 22347 | _x_x_mozilla_cycle_collect,381 04:37:09 INFO - PROCESS | 22347 | __end_cc_report 04:37:09 INFO - PROCESS | 22347 | __startTimestamp1487335029242__endTimestamp 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | ------- Summary: start ------- 04:37:09 INFO - PROCESS | 22347 | Number of tests: 6 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#0] dojo.html Cycles:165 Average:4770.15 Median:4888.17 stddev:515.44 (10.5%) stddev-sans-first:487.97 04:37:09 INFO - PROCESS | 22347 | Values: 2595.5 2620.4 2632.7 2636.1 2663.5 4731.0 4885.1 4893.2 4912.9 4923.8 4757.1 4761.3 4835.6 4864.0 4866.8 4333.5 4380.4 4440.6 4464.3 4473.0 4128.4 4532.6 4553.0 4566.8 4606.8 4277.8 4289.3 4494.3 4525.8 4545.7 4364.3 4414.2 4431.8 4492.6 4496.3 4374.1 4387.8 4407.3 4408.0 4448.0 4772.5 4822.4 4826.2 4834.1 4873.8 4986.9 5043.1 5180.2 5221.6 5245.3 5296.2 5298.2 5312.1 5314.4 5351.4 4926.2 4935.5 4945.7 4956.9 5002.8 5085.8 5133.2 5316.8 5352.3 5390.9 5153.0 5168.6 5169.3 5209.6 5222.9 4961.7 4984.2 5080.9 5086.1 5235.7 5091.2 5136.3 5169.4 5169.8 5173.8 4888.2 4952.9 5119.9 5133.7 5146.0 4290.7 4328.6 4331.4 4337.8 4342.6 3764.5 3815.4 3816.9 3819.5 3822.9 4414.9 4488.4 4516.8 4528.3 4561.6 5228.8 5259.3 5267.9 5272.0 5282.4 4522.8 4527.4 4537.7 4576.1 4584.5 4490.1 4531.0 4612.3 4629.3 4636.4 4820.4 4822.9 4827.2 4851.7 4865.5 5069.3 5171.4 5173.8 5178.7 5189.3 4744.7 4786.8 4825.8 4858.6 4889.5 5020.4 5064.8 5119.2 5124.8 5158.3 5172.5 5184.7 5197.9 5201.2 5262.3 4774.2 4804.5 5020.9 5051.3 5053.6 5005.4 5035.7 5043.1 5062.9 5135.1 4724.0 4956.9 4985.5 5014.6 5057.0 4846.2 5009.9 5065.2 5065.7 5142.5 4756.1 4803.5 4916.3 4962.7 4968.3 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#1] ext.html Cycles:180 Average:43788.62 Median:36867.22 stddev:74834.75 (203.0%) stddev-sans-first:74974.13 04:37:09 INFO - PROCESS | 22347 | Values: 510.8 536.3 537.1 557.1 562.2 38051.6 38998.4 39276.8 42681.0 43058.6 39950.4 40189.8 40528.0 40641.0 40642.8 7664.2 7827.0 7869.0 9143.6 9486.5 9337.3 9380.4 9421.0 9425.9 9435.5 9446.5 9513.7 9529.3 9567.0 9568.6 9399.2 9441.7 9466.8 9469.4 9473.7 9251.9 9258.9 9273.8 9286.5 9334.9 33078.0 33164.3 33199.2 33208.3 33262.0 3337.2 3431.0 3441.7 3467.3 3507.8 25074.5 25145.7 25275.6 25398.4 25684.0 3356.4 3478.7 3502.7 3644.7 3645.0 224710.0 225213.9 225268.0 225968.0 226450.0 39086.0 40061.8 45825.8 46230.3 46765.0 45746.3 49641.0 49915.0 50078.5 50119.5 12319.4 12446.1 12505.8 12515.3 12517.6 9157.4 9240.4 9242.4 9256.7 9276.0 15606.2 15624.9 15705.5 15743.4 15789.9 6469.5 6505.2 6583.9 6602.8 6632.0 754.2 775.8 778.2 796.7 797.2 25159.0 26486.5 26633.3 26697.3 26976.3 894.0 911.3 922.4 928.8 1085.4 14102.2 14203.6 14217.3 14257.7 14326.8 322761.0 324037.0 326133.0 326819.0 327155.0 47494.0 48063.5 48341.5 48392.3 48420.3 1192.3 1209.3 1215.3 1216.7 1219.9 22935.7 23249.2 23558.3 23815.9 23863.8 41890.0 42412.0 42683.0 42721.8 42728.4 295996.0 296212.0 296576.0 298869.0 299189.0 25231.6 36018.6 38226.8 38719.8 42245.6 37839.2 38042.6 39561.4 41365.0 41787.0 35213.3 36588.6 36778.6 36955.8 37789.4 35140.6 35518.5 35633.3 35749.8 35945.5 34216.7 34837.7 35025.0 35840.8 36237.6 30142.5 32693.5 32835.3 32947.5 32968.3 24753.5 25200.0 25375.9 25618.0 25718.2 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#2] jquery.html Cycles:180 Average:25950.87 Median:29020.95 stddev:75365.89 (259.7%) stddev-sans-first:75552.87 04:37:09 INFO - PROCESS | 22347 | Values: 388.3 388.8 393.5 395.0 415.7 12227.4 14988.9 16469.9 17941.6 17953.9 16975.5 18056.9 18177.5 18313.2 18426.4 5082.6 5140.3 5206.8 5257.7 5357.5 4330.7 4331.8 4352.8 4457.4 5172.8 5466.1 5466.5 5468.3 5476.2 5481.4 5281.4 5413.9 5442.8 5446.8 5447.2 5313.5 5324.1 5331.0 5335.7 5350.9 12056.0 12134.7 12300.1 12936.5 20828.4 2092.5 2116.5 2158.1 2196.9 2235.6 9313.8 9369.7 9378.3 9875.4 9926.3 1645.7 1718.9 1729.6 1730.3 1757.8 62693.7 75727.0 75998.0 76307.6 77954.2 9920.4 10353.9 10678.4 10689.2 10991.7 12044.9 15256.5 15296.0 15317.0 15330.1 6382.9 6403.2 6672.0 6710.3 6728.6 6039.5 6128.1 6197.3 6244.9 6282.5 3975.0 3987.2 3988.1 4016.5 4089.9 1951.3 1962.3 2000.9 2001.2 2022.9 793.9 812.8 821.6 831.2 848.6 15516.6 16238.8 16302.8 16656.3 17297.9 799.5 805.2 815.4 816.4 826.4 8914.2 8960.3 9441.9 9447.4 9544.8 448941.0 449855.0 453358.0 467803.0 470716.0 62181.4 63112.4 72588.0 85581.6 86965.6 911.8 913.6 940.5 940.6 950.8 14462.9 14750.6 14788.3 15069.8 15462.1 36851.4 37169.6 37203.3 37446.4 37516.8 46297.3 47698.3 48676.3 52760.5 53178.5 7547.4 7688.7 7695.0 7745.1 7996.2 11421.6 11732.2 12227.1 12508.6 12592.7 9535.5 9555.4 9575.9 10212.0 10232.9 9878.1 9898.4 9999.8 10281.3 10496.1 8924.2 9503.7 9544.8 9547.1 9918.4 7380.5 7523.7 7824.5 8004.7 8135.5 8615.9 9489.3 9550.6 9731.6 9966.8 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#3] mootools.html Cycles:180 Average:4790.61 Median:4982.53 stddev:2620.74 (52.6%) stddev-sans-first:2615.11 04:37:09 INFO - PROCESS | 22347 | Values: 1320.2 1343.5 1361.8 1366.1 1367.2 7015.3 7070.4 7126.4 7481.7 7490.7 7328.7 7446.4 7448.1 7449.5 7471.2 2106.8 2120.2 2137.6 2141.5 2144.2 2079.3 2123.9 2126.0 2128.1 2138.7 2050.4 2053.0 2067.3 2075.5 2080.9 2055.2 2065.8 2070.8 2072.9 2074.1 1741.1 1799.9 2039.1 2077.5 2114.0 7242.3 7405.4 7409.8 7409.9 7421.7 4404.7 4525.1 4530.8 4531.9 4537.4 2699.0 2849.9 2907.4 2909.3 2922.9 1176.5 1205.6 1655.6 1696.6 1844.2 9846.0 9856.9 9893.1 9931.9 9957.7 3934.2 4012.0 4030.6 4045.9 4050.7 7974.4 7989.8 7993.7 7999.0 8054.1 4442.5 4444.4 4460.5 4504.4 4508.9 4030.5 4104.2 4173.1 4173.8 4188.7 3215.2 3228.9 3296.4 3375.2 3381.2 973.2 992.3 997.8 1004.4 1014.1 757.2 772.3 780.4 785.9 787.4 4767.5 4770.1 4898.8 4910.3 5054.8 520.1 524.0 526.2 539.4 539.6 3068.8 3089.7 3136.6 3168.4 3199.7 8443.2 8856.2 8922.0 8951.6 8963.3 8668.5 8747.5 8867.2 8877.6 8898.9 6778.1 6863.4 6868.0 6873.1 6898.8 4662.0 4665.9 4668.9 4694.7 4767.0 7705.6 7869.5 7896.8 7915.7 8109.9 7749.3 7858.1 8045.8 8211.8 8255.5 6396.2 6475.2 6493.4 6592.4 6601.8 6980.8 7004.5 7023.0 7023.8 7046.9 6308.6 6366.0 6408.5 6417.6 6553.9 6337.0 6393.5 6450.2 6569.9 6607.3 5485.4 5568.9 5582.6 5584.2 5616.5 4229.4 4289.0 4343.3 4375.0 4424.3 4233.8 4269.1 4296.3 4317.1 4357.4 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#4] prototype.html Cycles:180 Average:4362.31 Median:4897.49 stddev:1217.04 (24.9%) stddev-sans-first:1182.78 04:37:09 INFO - PROCESS | 22347 | Values: 358.9 364.1 368.1 375.9 377.8 4894.8 4899.9 4930.4 4944.0 5040.9 4883.6 4961.5 4967.6 5006.5 5007.0 3886.2 3978.4 4005.7 4020.4 4040.3 3987.6 3990.4 3994.2 4010.6 4029.4 3988.8 3991.9 3995.8 4009.8 4021.8 3859.3 3945.5 3995.9 4013.9 4014.3 3552.4 3565.9 3577.7 3580.8 3604.3 4820.3 4897.5 4912.7 4919.2 4924.8 4574.9 4600.6 4603.1 4616.8 4641.4 5008.3 5192.3 5202.8 5213.5 5219.8 3976.5 3981.0 3987.0 3996.8 3996.8 5229.6 5341.3 5513.2 5520.0 5521.8 3927.8 3992.4 4010.2 4018.2 4020.5 4090.3 4091.8 4108.7 4117.4 4134.3 4535.7 4578.3 4585.4 4588.5 4592.7 4980.9 5236.9 5277.4 5306.2 5355.3 1399.0 1402.5 1405.6 1413.5 1420.6 973.8 1015.3 1022.7 1025.9 1046.2 4418.4 4446.2 4456.5 4457.0 4464.4 5156.6 5178.3 5180.5 5245.3 5272.2 4281.8 4445.9 4447.0 4463.6 4470.2 2751.3 2775.2 2779.2 2786.0 2797.6 4887.3 4897.5 4912.8 4923.9 4930.9 5062.9 5348.2 5373.8 5395.8 5432.7 5001.5 5026.5 5037.6 5040.0 5051.7 5386.3 5409.8 5431.4 5447.3 5452.9 5153.3 5336.4 5339.0 5342.3 5361.2 4818.9 4850.2 4981.2 5068.5 5072.9 3742.4 3792.6 3796.3 3802.3 3811.7 5355.6 5371.2 5376.4 5383.0 5386.3 4984.7 5185.6 5226.5 5230.2 5251.4 5177.0 5199.9 5204.6 5221.3 5226.9 5085.2 5101.9 5102.6 5124.5 5157.7 4940.8 5223.5 5276.3 5276.8 5314.1 5117.9 5135.3 5140.3 5157.2 5159.9 04:37:09 INFO - PROCESS | 22347 | 04:37:09 INFO - PROCESS | 22347 | [#5] yui.html Cycles:180 Average:14068.10 Median:49729.75 stddev:17212.00 (34.6%) stddev-sans-first:17230.80 04:37:09 INFO - PROCESS | 22347 | Values: 651.2 680.7 685.6 694.9 741.1 12570.7 13064.2 13297.9 13880.7 13905.3 11504.8 11918.8 11949.6 11971.1 12072.2 608.9 616.2 799.0 806.6 811.8 813.6 830.6 830.9 831.1 832.8 855.0 855.8 856.2 857.7 860.2 851.7 855.1 856.9 857.9 858.2 616.9 620.4 621.1 623.7 623.8 13225.5 13326.3 13330.4 13461.3 14519.9 13233.4 13716.9 13942.2 13985.5 14189.4 10828.9 10834.5 10875.3 10927.6 11055.6 3196.8 3216.2 3217.8 3238.7 3241.9 59635.4 59842.4 60300.0 60377.1 60669.0 10350.4 10364.1 10646.7 10684.5 10967.7 20344.4 20352.3 20414.6 20524.4 20677.3 6635.4 6665.8 6752.4 6781.3 6793.8 3503.2 3512.9 3520.9 3603.4 3607.4 2961.6 2988.4 3028.4 3052.9 3063.5 473.9 474.3 482.0 482.2 488.0 636.5 641.8 653.6 664.2 667.6 8193.4 8260.8 8444.7 8475.7 8630.0 559.2 581.9 585.1 590.5 591.2 4116.0 4259.6 4287.7 4309.6 4381.0 57460.4 57578.0 59162.4 61679.4 61939.4 45990.0 49998.0 53571.0 56308.7 57519.7 49223.5 49454.0 49704.8 49754.8 50066.7 8049.4 8235.8 8298.7 8430.7 8459.8 33573.8 33683.0 33768.3 35533.6 36154.1 39625.6 42556.4 43085.0 44207.0 44904.3 10142.1 10307.9 10314.6 10315.2 10412.8 11311.2 11410.5 11467.2 11711.4 12163.1 10037.4 10072.6 10153.5 10222.5 10621.1 9713.0 9832.6 9890.8 9894.4 10151.0 9537.6 9607.5 9866.7 9893.2 10273.8 8539.5 8891.1 8913.0 8917.8 9187.8 7380.9 7593.2 7775.9 8239.8 8362.0 04:37:09 INFO - PROCESS | 22347 | -------- Summary: end -------- 04:37:09 INFO - PROCESS | 22347 | 04:37:10 INFO - TEST-INFO | 22347: exit 0 04:37:10 INFO - TEST-OK | dromaeo_css | took 1121098ms 04:37:10 INFO - TEST-START | kraken 04:37:10 INFO - operating with platform_type : linux_ 04:37:10 INFO - Initialising browser for kraken test... 04:37:10 INFO - TEST-INFO | started process 22720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA0DI10/profile http://localhost:42837/getInfo.html) 04:37:13 INFO - PROCESS | 22720 | Iterating over profile /tmp/tmpA0DI10/profile 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/extensions.json = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/user.js = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/localstore.rdf = Fri Feb 17 2017 04:17:49 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/places.sqlite-wal = Fri Feb 17 2017 04:37:13 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/blocklist.xml = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/cert8.db = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:13 INFO - PROCESS | 22720 | Stat failure 04:37:13 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:13 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/extensions = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/places.sqlite = Fri Feb 17 2017 04:37:13 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/gmp = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/addons.json = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/prefs.js = Fri Feb 17 2017 04:17:49 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/places.sqlite-shm = Fri Feb 17 2017 04:37:11 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/crashes = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/bookmarkbackups = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/secmod.db = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/compatibility.ini = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/cache2 = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/thumbnails = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/pluginreg.dat = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/safebrowsing = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/.parentlock = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/sessionCheckpoints.json = Fri Feb 17 2017 04:37:13 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/minidumps = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/content-prefs.sqlite = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/key3.db = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/permissions.sqlite = Fri Feb 17 2017 04:37:11 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/startupCache = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/frequencyCap.json = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/extensions.ini = Fri Feb 17 2017 04:37:10 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | No birth date. Using mtime. 04:37:14 INFO - PROCESS | 22720 | Using date: /tmp/tmpA0DI10/profile/directoryLinks.json = Fri Feb 17 2017 04:37:12 GMT-0800 (PST) 04:37:14 INFO - PROCESS | 22720 | __metrics Screen width/height:1600/1200 04:37:14 INFO - PROCESS | 22720 | colorDepth:24 04:37:14 INFO - PROCESS | 22720 | Browser inner width/height: 1024/697 04:37:14 INFO - PROCESS | 22720 | __metrics 04:37:15 ERROR - PROCESS | 22720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:37:16 INFO - TEST-INFO | 22720: exit 0 04:37:16 INFO - Browser initialized. 04:37:16 INFO - Running cycle 1/1 for kraken test... 04:37:16 INFO - TEST-INFO | started process 22851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA0DI10/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:28 INFO - PROCESS | 22851 | RSS: Main: 153931776 04:37:28 INFO - PROCESS | 22851 | 04:37:31 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42837/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:32 INFO - PROCESS | 22851 | RSS: Main: 160514048 04:37:32 INFO - PROCESS | 22851 | 04:37:35 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42837/tests/kraken/driver.html?testName=audio-dft) 04:37:35 INFO - PROCESS | 22851 | RSS: Main: 158408704 04:37:35 INFO - PROCESS | 22851 | 04:37:38 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42837/tests/kraken/driver.html?testName=audio-fft) 04:37:39 INFO - PROCESS | 22851 | RSS: Main: 155783168 04:37:39 INFO - PROCESS | 22851 | 04:37:41 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42837/tests/kraken/driver.html?testName=audio-oscillator) 04:37:42 INFO - PROCESS | 22851 | RSS: Main: 156127232 04:37:42 INFO - PROCESS | 22851 | 04:37:44 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42837/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:45 INFO - PROCESS | 22851 | RSS: Main: 156516352 04:37:45 INFO - PROCESS | 22851 | 04:37:48 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42837/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:48 INFO - PROCESS | 22851 | RSS: Main: 161042432 04:37:48 INFO - PROCESS | 22851 | 04:37:52 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42837/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:53 INFO - PROCESS | 22851 | RSS: Main: 157941760 04:37:53 INFO - PROCESS | 22851 | 04:37:55 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42837/tests/kraken/driver.html?testName=json-parse-financial) 04:37:56 INFO - PROCESS | 22851 | RSS: Main: 158924800 04:37:56 INFO - PROCESS | 22851 | 04:37:58 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42837/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:58 INFO - PROCESS | 22851 | RSS: Main: 159346688 04:37:58 INFO - PROCESS | 22851 | 04:38:00 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:38:01 INFO - PROCESS | 22851 | RSS: Main: 159055872 04:38:01 INFO - PROCESS | 22851 | 04:38:03 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:38:04 INFO - PROCESS | 22851 | RSS: Main: 159068160 04:38:04 INFO - PROCESS | 22851 | 04:38:07 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:38:08 INFO - PROCESS | 22851 | RSS: Main: 159776768 04:38:08 INFO - PROCESS | 22851 | 04:38:11 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:38:11 INFO - PROCESS | 22851 | RSS: Main: 162734080 04:38:11 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | Cycle 1(1): loaded http://localhost:42837/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42837/tests/kraken/driver.html?testName=ai-astar) 04:38:13 INFO - PROCESS | 22851 | RSS: Main: 164323328 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | __start_tp_report 04:38:13 INFO - PROCESS | 22851 | _x_x_mozilla_page_load 04:38:13 INFO - PROCESS | 22851 | _x_x_mozilla_page_load_details 04:38:13 INFO - PROCESS | 22851 | |i|pagename|runs| 04:38:13 INFO - PROCESS | 22851 | |0;ai-astar;109;116;118;117;118;118;121;116;117;120 04:38:13 INFO - PROCESS | 22851 | |1;audio-beat-detection;163;130;133;174;133;133;132;127;158;127 04:38:13 INFO - PROCESS | 22851 | |2;audio-dft;166;166;167;169;168;165;164;165;163;165 04:38:13 INFO - PROCESS | 22851 | |3;audio-fft;77;78;78;109;80;80;79;78;95;76 04:38:13 INFO - PROCESS | 22851 | |4;audio-oscillator;86;85;115;87;86;88;102;88;87;114 04:38:13 INFO - PROCESS | 22851 | |5;imaging-gaussian-blur;114;114;114;115;114;116;114;114;115;114 04:38:13 INFO - PROCESS | 22851 | |6;imaging-darkroom;151;151;151;150;152;150;151;151;151;150 04:38:13 INFO - PROCESS | 22851 | |7;imaging-desaturate;96;97;96;96;96;97;96;96;96;96 04:38:13 INFO - PROCESS | 22851 | |8;json-parse-financial;64;67;68;66;66;68;69;65;67;67 04:38:13 INFO - PROCESS | 22851 | |9;json-stringify-tinderbox;69;66;65;66;66;66;65;66;65;66 04:38:13 INFO - PROCESS | 22851 | |10;stanford-crypto-aes;87;89;91;87;96;88;88;87;94;88 04:38:13 INFO - PROCESS | 22851 | |11;stanford-crypto-ccm;154;140;154;152;141;147;139;146;189;149 04:38:13 INFO - PROCESS | 22851 | |12;stanford-crypto-pbkdf2;189;188;189;191;197;187;194;187;192;188 04:38:13 INFO - PROCESS | 22851 | |13;stanford-crypto-sha256-iterative;77;71;74;73;77;75;74;75;78;76 04:38:13 INFO - PROCESS | 22851 | __end_tp_report 04:38:13 INFO - PROCESS | 22851 | __start_cc_report 04:38:13 INFO - PROCESS | 22851 | _x_x_mozilla_cycle_collect,886 04:38:13 INFO - PROCESS | 22851 | __end_cc_report 04:38:13 INFO - PROCESS | 22851 | __startTimestamp1487335093983__endTimestamp 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | ------- Summary: start ------- 04:38:13 INFO - PROCESS | 22851 | Number of tests: 14 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | [#0] ai-astar Cycles:10 Average:117.00 Median:118.00 stddev:3.23 (2.7%) stddev-sans-first:1.69 04:38:13 INFO - PROCESS | 22851 | Values: 109.0 116.0 118.0 117.0 118.0 118.0 121.0 116.0 117.0 120.0 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:133.00 stddev:17.15 (12.9%) stddev-sans-first:16.24 04:38:13 INFO - PROCESS | 22851 | Values: 163.0 130.0 133.0 174.0 133.0 133.0 132.0 127.0 158.0 127.0 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | [#2] audio-dft Cycles:10 Average:165.80 Median:166.00 stddev:1.81 (1.1%) stddev-sans-first:1.92 04:38:13 INFO - PROCESS | 22851 | Values: 166.0 166.0 167.0 169.0 168.0 165.0 164.0 165.0 163.0 165.0 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | [#3] audio-fft Cycles:10 Average:83.00 Median:78.50 stddev:10.61 (13.5%) stddev-sans-first:11.03 04:38:13 INFO - PROCESS | 22851 | Values: 77.0 78.0 78.0 109.0 80.0 80.0 79.0 78.0 95.0 76.0 04:38:13 INFO - PROCESS | 22851 | 04:38:13 INFO - PROCESS | 22851 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:86.50 stddev:11.94 (13.8%) stddev-sans-first:12.33 04:38:13 INFO - PROCESS | 22851 | Values: 86.0 85.0 115.0 87.0 86.0 88.0 102.0 88.0 87.0 114.0 04:38:13 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 04:38:14 INFO - PROCESS | 22851 | Values: 114.0 114.0 114.0 115.0 114.0 116.0 114.0 114.0 115.0 114.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 04:38:14 INFO - PROCESS | 22851 | Values: 151.0 151.0 151.0 150.0 152.0 150.0 151.0 151.0 151.0 150.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:38:14 INFO - PROCESS | 22851 | Values: 96.0 97.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:67.00 stddev:1.49 (2.2%) stddev-sans-first:1.22 04:38:14 INFO - PROCESS | 22851 | Values: 64.0 67.0 68.0 66.0 66.0 68.0 69.0 65.0 67.0 67.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:1.15 (1.7%) stddev-sans-first:0.50 04:38:14 INFO - PROCESS | 22851 | Values: 69.0 66.0 65.0 66.0 66.0 66.0 65.0 66.0 65.0 66.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.50 stddev:3.17 (3.6%) stddev-sans-first:3.23 04:38:14 INFO - PROCESS | 22851 | Values: 87.0 89.0 91.0 87.0 96.0 88.0 88.0 87.0 94.0 88.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:150.50 stddev:14.43 (9.6%) stddev-sans-first:15.26 04:38:14 INFO - PROCESS | 22851 | Values: 154.0 140.0 154.0 152.0 141.0 147.0 139.0 146.0 189.0 149.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.20 Median:190.00 stddev:3.29 (1.7%) stddev-sans-first:3.46 04:38:14 INFO - PROCESS | 22851 | Values: 189.0 188.0 189.0 191.0 197.0 187.0 194.0 187.0 192.0 188.0 04:38:14 INFO - PROCESS | 22851 | 04:38:14 INFO - PROCESS | 22851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:75.50 stddev:2.11 (2.8%) stddev-sans-first:2.11 04:38:14 INFO - PROCESS | 22851 | Values: 77.0 71.0 74.0 73.0 77.0 75.0 74.0 75.0 78.0 76.0 04:38:14 INFO - PROCESS | 22851 | -------- Summary: end -------- 04:38:14 INFO - PROCESS | 22851 | 04:38:15 INFO - TEST-INFO | 22851: exit 0 04:38:15 INFO - TEST-OK | kraken | took 65036ms 04:38:15 INFO - SUITE-END | took 1186s 04:38:15 INFO - Completed test suite (00:19:46) 04:38:15 INFO - javascript benchmark 04:38:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6293.815618417593, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2595.5198288316665, 2620.3580122869894, 2632.749888760567, 2636.1037247335903, 2663.5338815707246, 4731.006488637382, 4885.096257192422, 4893.241547560129, 4912.852614421568, 4923.753812309388, 4757.1437141314545, 4761.262004389317, 4835.564799168074, 4863.951360486394, 4866.756662166895, 4333.544977777336, 4380.386745855567, 4440.622547083502, 4464.263396539567, 4472.955270447303, 4128.422020917074, 4532.5694059064335, 4552.954470455303, 4566.817327306904, 4606.815727370901, 4277.786110694484, 4289.335153051282, 4494.280915053609, 4525.796339164745, 4545.749983750892, 4364.301711726141, 4414.249577571821, 4431.8448854289945, 4492.573205545468, 4496.303073023687, 4374.125174964988, 4387.824487020515, 4407.294832826765, 4408.008198155421, 4447.999200179966, 4772.522747725235, 4822.373091498082, 4826.155422800995, 4834.057358815033, 4873.780679869415, 4986.850394488171, 5043.067032598981, 5180.248863914749, 5221.556167725748, 5245.31810864589, 5296.232046353296, 5298.152295632719, 5312.096943519611, 5314.415414304423, 5351.411344752074, 4926.211806111005, 4935.481774413706, 4945.703257804471, 4956.90086198274, 5002.774875130628, 5085.771140298695, 5133.230015497706, 5316.840494785162, 5352.2506849041165, 5390.865228369322, 5153.0466863630345, 5168.586513078944, 5169.327987361619, 5209.557187639093, 5222.869428264324, 4961.6526843121355, 4984.22744474607, 5080.898382032413, 5086.059079070384, 5235.73821308933, 5091.159958606789, 5136.332276804066, 5169.353830771153, 5169.81905633301, 5173.844784656466, 4888.169011268093, 4952.90094198114, 5119.948800512022, 5133.717645529532, 5145.9742701287005, 4290.678199135077, 4328.588784065509, 4331.4152589400655, 4337.783110844445, 4342.5874541918465, 3764.4917936078828, 3815.42768584709, 3816.866409675648, 3819.52255968004, 3822.9426558601645, 4414.889627759268, 4488.4165058542185, 4516.841910533116, 4528.298113792367, 4561.566651168134, 5228.790848366023, 5259.342582177228, 5267.894642107213, 5272.0, 5282.418933917266, 4522.796474158671, 4527.366168736379, 4537.705049171794, 4576.130535198302, 4584.518625544337, 4490.057088011489, 4531.048479819209, 4612.285095810154, 4629.305604159403, 4636.374089497875, 4820.373351464287, 4822.903541929071, 4827.179379505493, 4851.660383773102, 4865.489123642037, 5069.264956581349, 5171.353580802249, 5173.81891633791, 5178.71517066565, 5189.325387699575, 4744.715317080986, 4786.808527658994, 4825.758712064453, 4858.611311095033, 4889.535494128052, 5020.3724534433195, 5064.848054558369, 5119.232115182753, 5124.7950081996305, 5158.329417175743, 5172.508611681884, 5184.688918664892, 5197.896042079213, 5201.193814958688, 5262.315898933114, 4774.164521208844, 4804.471508134033, 5020.899582008266, 5051.343325367826, 5053.620978426559, 5005.374328208974, 5035.723035233097, 5043.092243396077, 5062.949370506395, 5135.101357262539, 4723.976380118077, 4956.925646115239, 4985.501449855131, 5014.623903207347, 5056.974715126401, 4846.151923413459, 5009.9248511271635, 5065.164247899055, 5065.721385323843, 5142.485751424828, 4756.072565849626, 4803.543663351976, 4916.3362946001835, 4962.652614317108, 4968.27959945813], "value": 4736.218797242257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.79823469729433, 536.3322663284212, 537.1352123081838, 557.0863783395231, 562.1988666150734, 38051.61948380515, 38998.41502377463, 39276.80361598195, 42681.0, 43058.56941430585, 39950.40074398887, 40189.799051004775, 40528.0, 40641.0, 40642.79678601603, 7664.19525949777, 7827.0, 7869.0, 9143.588538515749, 9486.478243696629, 9337.346385753, 9380.390274632162, 9420.952895235549, 9425.905740942606, 9435.528223588793, 9446.480443575601, 9513.71458856232, 9529.285303602222, 9567.0, 9568.617255309779, 9399.201067909236, 9441.716748497556, 9466.810663786686, 9469.431834089977, 9473.715788526355, 9251.907480925172, 9258.861117083248, 9273.814523709556, 9286.489243091628, 9334.906650933472, 33078.04595172868, 33164.33671326584, 33199.170020749436, 33208.3358332832, 33262.0, 3337.1657085728566, 3430.9656903430896, 3441.6730410611203, 3467.289205712823, 3507.8070706111153, 25074.48659241336, 25145.748542514706, 25275.62086568703, 25398.36504087394, 25684.0, 3356.3790698720813, 3478.7043101336417, 3502.6847583717586, 3644.69020133289, 3645.0, 224710.0, 225213.87393063094, 225268.0, 225968.0, 226450.0, 39086.0, 40061.79969100136, 45825.77087114543, 46230.306545401174, 46765.0, 45746.31380529228, 49641.0, 49915.0, 50078.49921500811, 50119.49880501221, 12319.384030798425, 12446.128770986124, 12505.812412813815, 12515.311657858905, 12517.56188533397, 9157.358984871129, 9240.399374040666, 9242.353035287466, 9256.72229833106, 9275.953620231856, 15606.21968901573, 15624.92187539055, 15705.450309239122, 15743.370265189493, 15789.921050394903, 6469.482441404676, 6505.154329937078, 6583.868322633616, 6602.8349291268105, 6632.0, 754.2080815144077, 775.8099265679945, 778.1867947994359, 796.6733639208013, 797.1709422200962, 25159.0, 26486.47027059448, 26633.33416664599, 26697.33256668599, 26976.32559186036, 893.9642414303355, 911.2633739551163, 922.4004397141912, 928.8328100941895, 1085.4301491716913, 14102.224377659122, 14203.573892783234, 14217.32076560926, 14257.714845703027, 14326.785098223534, 322761.0, 324037.0, 326133.0, 326819.0, 327155.0, 47494.0, 48063.519364806605, 48341.516584834404, 48392.27411588829, 48420.27369589459, 1192.3322939154102, 1209.3106929050532, 1215.2769102384068, 1216.6654170103293, 1219.945102470391, 22935.65596516087, 23249.18627848017, 23558.293251201805, 23815.880920595286, 23863.761362386154, 41890.0, 42412.0, 42683.0, 42721.78639106909, 42728.35907461328, 295996.0, 296212.0, 296576.0, 298869.0, 299189.0, 25231.62152567676, 36018.55925763045, 38226.808865956606, 38719.80640096781, 42245.577544224165, 37839.24321513609, 38042.61957380391, 39561.406578900765, 41365.0, 41787.0, 35213.29573408569, 36588.634113658525, 36778.63221367752, 36955.81522092372, 37789.43315850209, 35140.64859351374, 35518.46722299219, 35633.28733425265, 35749.821250893576, 35945.46081808827, 34216.65783342135, 34837.65162348445, 35025.0, 35840.82079589585, 36237.63762362448, 30142.54786178253, 32693.509597355584, 32835.34329313448, 32947.50578741273, 32968.34063318768, 24753.53450516965, 25200.0, 25375.873120634278, 25618.0, 25718.228453146436], "value": 17177.049935174426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.3133374844944, 388.78811047978866, 393.4609584868729, 394.95063117110357, 415.6612360925847, 12227.449764760584, 14988.850111498881, 16469.917650411746, 17941.551461213476, 17953.910230448844, 16975.49073527793, 18056.90971545144, 18177.54556136094, 18313.17590708418, 18426.44720658379, 5082.618803589727, 5140.2546630738525, 5206.843794686166, 5257.658252213594, 5357.464253574651, 4330.696851220416, 4331.826726930919, 4352.804123814436, 4457.442819647544, 5172.793088276464, 5466.070767969435, 5466.453354664542, 5468.28912241408, 5476.17857321403, 5481.369642491125, 5281.419043905167, 5413.918791218135, 5442.83671489854, 5446.782128714846, 5447.155690867922, 5313.468653134694, 5324.068288049614, 5331.0, 5335.653182543122, 5350.866228344284, 12056.0, 12134.696632584168, 12300.077494187883, 12936.547220847226, 20828.375148745563, 2092.47317182482, 2116.502621883856, 2158.115172779153, 2196.901139448729, 2235.564065007324, 9313.813723725487, 9369.672061477815, 9378.296627752878, 9875.40747555149, 9926.305158638897, 1645.6626391589696, 1718.9140542972802, 1729.6281299520663, 1730.3078768492578, 1757.8154293799096, 62693.68653156705, 75727.0, 75998.0, 76307.61846190788, 77954.22045779527, 9920.355176913477, 10353.912839151932, 10678.437175078081, 10689.198310126772, 10991.725206869893, 12044.939775301067, 15256.466023689118, 15296.0, 15317.0, 15330.080195188324, 6382.904256436157, 6403.231612206472, 6671.966640166866, 6710.328967103251, 6728.596284222962, 6039.456448919618, 6128.1114238435175, 6197.326721785183, 6244.906326405107, 6282.465990390777, 3975.0, 3987.169109931923, 3988.102676897675, 4016.5180178378782, 4089.9386509202386, 1951.3267922566693, 1962.2837664252731, 2000.8699434536709, 2001.2295266322571, 2022.8583999120215, 793.9126696063429, 812.7968007998, 821.6343727041528, 831.2477208126655, 848.6266042941086, 15516.612084697746, 16238.837611623969, 16302.755458668129, 16656.33374665024, 17297.91351043262, 799.5122974985254, 805.1907832628289, 815.4454970620035, 816.3877092180865, 826.4338927834452, 8914.242289405343, 8960.283177345797, 9441.858372124423, 9447.385919915183, 9544.809103818023, 448941.0, 449855.0, 453358.0, 467803.0, 470716.0, 62181.37818621846, 63112.36887631065, 72588.0, 85581.57209213915, 86965.56517217374, 911.7811725185908, 913.6071489259682, 940.5062342270226, 940.6331530703031, 950.8003319303024, 14462.85537144615, 14750.63123421923, 14788.299345574966, 15069.773953390928, 15462.072275663497, 36851.447228292134, 37169.628303716614, 37203.255934881694, 37446.43830342602, 37516.81241593884, 46297.3055404176, 47698.28452573284, 48676.26985595148, 52760.472395277095, 53178.4682153189, 7547.358474529727, 7688.730894418669, 7694.961525192338, 7745.109312429143, 7996.160403157584, 11421.600243991752, 11732.237404568676, 12227.14409991292, 12508.562200322946, 12592.685182870502, 9535.52322383892, 9555.378900371454, 9575.904240957501, 10212.0, 10232.897671023195, 9878.065147907613, 9898.406095634286, 9999.800004000024, 10281.280310378203, 10496.107830834158, 8924.152205540464, 9503.66737164196, 9544.761380965532, 9547.093026162504, 9918.355306905029, 7380.483366164532, 7523.699052037857, 7824.491408058458, 8004.679812807422, 8135.511869287861, 8615.91384086151, 9489.288303377136, 9550.570224340061, 9731.562079706571, 9966.800663986534], "value": 8072.168414716052, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.1880843281385, 1343.5297645823962, 1361.836579610447, 1366.0574203799374, 1367.1728604194461, 7015.333543313377, 7070.363667269945, 7126.3942564882045, 7481.663325150368, 7490.700371985114, 7328.743493977712, 7446.367058799995, 7448.106227252723, 7449.478536502447, 7471.215522370155, 2106.7893210678926, 2120.151939224307, 2137.593857167141, 2141.518158414352, 2144.185209620342, 2079.3034333498294, 2123.851330406872, 2126.0, 2128.095559387262, 2138.721966144407, 2050.4361300642295, 2052.9897350513224, 2067.286786058815, 2075.4811297175706, 2080.916763329465, 2055.2087446333194, 2065.845061620377, 2070.8032736889973, 2072.8549001569822, 2074.1288658763356, 1741.077229068583, 1799.9280028798833, 2039.1129858511447, 2077.522169900916, 2114.0486780948636, 7242.275772422769, 7405.370543503808, 7409.814754631124, 7409.925900741031, 7421.703131874719, 4404.669649776248, 4525.117602067598, 4530.841420550265, 4531.909361812778, 4537.387452693877, 2698.973010269892, 2849.9002534911388, 2907.360380716239, 2909.3163106669917, 2922.8976985805607, 1176.5176277726177, 1205.5961252980262, 1655.5943793770482, 1696.6012986948058, 1844.1793401936143, 9846.0, 9856.852147217798, 9893.109620134222, 9931.90068099317, 9957.701268961942, 3934.1934903344745, 4012.0371110933165, 4030.556638769733, 4045.93931091028, 4050.6556942660204, 7974.401919856034, 7989.840203195903, 7993.72021979228, 7998.960005199937, 8054.114047454776, 4442.466903971479, 4444.444444444444, 4460.50934397216, 4504.436945381827, 4508.864734057984, 4030.5163380393956, 4104.158647477319, 4173.144505376391, 4173.770442625685, 4188.706790524634, 3215.1640573450604, 3228.935421291561, 3296.423125952949, 3375.240570871534, 3381.2223188666744, 973.2360097323567, 992.2805965674828, 997.7755005123789, 1004.3772860826189, 1014.0974532665933, 757.1633345153637, 772.2702046566053, 780.4199970213023, 785.9331956783737, 787.4055088408215, 4767.4994125617, 4770.069836381942, 4898.804047838046, 4910.288008238786, 5054.772535235923, 520.0612893726784, 524.0357741755189, 526.1975487381696, 539.3743257820909, 539.6222644149033, 3068.7698422617946, 3089.675584063641, 3136.6079240094987, 3168.350488149924, 3199.696028877253, 8443.155684431507, 8856.158664926821, 8921.955390223136, 8951.552422378854, 8963.327750418614, 8668.479891206547, 8747.475151490931, 8867.246284065797, 8877.644894204159, 8898.911010889939, 6778.118844550276, 6863.38229559342, 6868.0, 6873.1064961554675, 6898.758543450955, 4661.976690116528, 4665.906681866411, 4668.85993420198, 4694.694844835075, 4766.976165119152, 7705.614719263902, 7869.5278283300895, 7896.802579935547, 7915.683372665029, 8109.878351824846, 7749.302562769378, 7858.135605083322, 8045.758627241192, 8211.753647390588, 8255.545944973084, 6396.232452105591, 6475.222973243241, 6493.448056915215, 6592.406683398517, 6601.834954126185, 6980.755673551402, 7004.544704594185, 7022.964885175541, 7023.754168604074, 7046.894296585453, 6308.589941653778, 6365.9681701593045, 6408.519361047846, 6417.647029413427, 6553.868922621426, 6336.968315158395, 6393.456556192776, 6450.161479007699, 6569.9014514783275, 6607.339266073354, 5485.396606373375, 5568.888622227451, 5582.637128586629, 5584.246126772996, 5616.494515493625, 4229.365595160751, 4288.978555107205, 4343.3267843483045, 4374.978125109354, 4424.274532932095, 4233.78831058452, 4269.082147338338, 4296.2911119664905, 4317.050248945227, 4357.433533640733], "value": 3874.430963786292, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8833629070553, 364.14244454310113, 368.1459015085004, 375.93233218020765, 377.7752237418735, 4894.8286809961655, 4899.853004409864, 4930.433000204977, 4943.950560494394, 5040.924386134211, 4883.584895283905, 4961.477351080728, 4967.565892712572, 5006.499350064992, 5007.047251571881, 3886.2227554489073, 3978.428520734308, 4005.6795456363566, 4020.43713880057, 4040.3131467650496, 3987.584407535312, 3990.361542153256, 3994.221126880245, 4010.6390424861756, 4029.415734718464, 3988.760674359548, 3991.900202494932, 3995.8002099895034, 4009.7594144351287, 4021.8391264349393, 3859.3246181918335, 3945.506811648544, 3995.9001024974464, 4013.8795836124964, 4014.257362387953, 3552.3960926642535, 3565.8751943682105, 3577.656634904142, 3580.8030558319283, 3604.333198358312, 4820.349252850855, 4897.461279259279, 4912.69103521348, 4919.163742163841, 4924.827631032933, 4574.931376029363, 4600.608948239404, 4603.056373443436, 4616.76916154191, 4641.373414589021, 5008.323876276692, 5192.299039629639, 5202.843914682566, 5213.4525874782985, 5219.817306394296, 3976.5029371328583, 3980.960190398088, 3986.980065099656, 3996.8201430935387, 3996.840126394941, 5229.555487783543, 5341.305630268041, 5513.2005859150195, 5519.972400137974, 5521.834344969658, 3927.842886284574, 3992.4210989406374, 4010.232939271893, 4018.2365350583295, 4020.4773379460485, 4090.284200264942, 4091.7954102294766, 4108.732932359447, 4117.382392641128, 4134.297169481196, 4535.705179163344, 4578.2903649934315, 4585.403897493305, 4588.485799686096, 4592.67851250416, 4980.875478113004, 5236.895262094813, 5277.445868183862, 5306.230596563554, 5355.30381050461, 1398.9720205595831, 1402.5301523989374, 1405.5994041698068, 1413.462884104034, 1420.6164335629528, 973.8393165127816, 1015.28422462164, 1022.7443139215196, 1025.8922813104666, 1046.1526163807341, 4418.403515525363, 4446.199684056836, 4456.509783923766, 4456.955430445654, 4464.419625448671, 5156.561692256125, 5178.300929374562, 5180.533751962342, 5245.26566280721, 5272.235525848807, 4281.828726850953, 4445.911081778347, 4446.954400975587, 4463.620592249695, 4470.2177119004045, 2751.257160566636, 2775.2368098773, 2779.2357101797165, 2785.958210626842, 2797.552391617372, 4887.338304976325, 4897.510248975145, 4912.754362281871, 4923.90152196954, 4930.8767280818265, 5062.924056139161, 5348.197770334481, 5373.758180881791, 5395.811146609928, 5432.674039557639, 5001.474845141208, 5026.472220416877, 5037.622178336639, 5040.018385475433, 5051.671641343374, 5386.253010964358, 5409.83770486886, 5431.402545719889, 5447.346318441812, 5452.918206226989, 5153.278541004337, 5336.3863155736035, 5338.9589030140305, 5342.252084708066, 5361.169018802092, 4818.903621927471, 4850.223964165716, 4981.2030075187795, 5068.516426950841, 5072.923906141485, 3742.401215805458, 3792.577783331251, 3796.335641262823, 3802.258559580855, 3811.6569508744346, 5355.571554275571, 5371.248025276543, 5376.354837419534, 5383.039328369303, 5386.272853164929, 4984.675996060245, 5185.637005409585, 5226.529612334908, 5230.163173892159, 5251.396089449762, 5176.9741151294, 5199.870003249797, 5204.635675502678, 5221.295125158055, 5226.947730522646, 5085.186370180753, 5101.897962040813, 5102.642815003062, 5124.538791508782, 5157.742112894265, 4940.838902857898, 5223.4515375884985, 5276.311580294422, 5276.815311464234, 5314.12316967696, 5117.948820511896, 5135.2553879687985, 5140.280360749423, 5157.226416037624, 5159.896802064013], "value": 3998.916544254907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.2380514797692, 680.6970897950414, 685.5509641185024, 694.8575542013889, 741.0736579275905, 12570.748585028294, 13064.150830196031, 13297.933510332436, 13880.652983675413, 13905.304734763273, 11504.769904601924, 11918.821217681741, 11949.58176463824, 11971.102167337442, 12072.215306005104, 608.8660494691183, 616.214326733414, 799.0371602219341, 806.5725165662209, 811.8254575266317, 813.633864760857, 830.6386721776016, 830.900291964965, 831.0692024932084, 832.8209434971479, 855.0252711908429, 855.84166929118, 856.2336708645759, 857.7298151082421, 860.1613426908746, 851.7104184577212, 855.0722466124263, 856.867185586232, 857.9099194584572, 858.1632907914765, 616.9198004259463, 620.3982137326785, 621.139721485741, 623.7193263031654, 623.8222106699569, 13225.537106201333, 13326.333683315892, 13330.400131994083, 13461.259630720306, 14519.927400363036, 13233.404496797668, 13716.862831371658, 13942.163470191821, 13985.510507132303, 14189.36147873348, 10828.945855270753, 10834.458277086114, 10875.293105948089, 10927.617533386372, 11055.557777688882, 3196.8081915084936, 3216.163797412666, 3217.8230197339135, 3238.6923242291946, 3241.9351612967844, 59635.403645963415, 59842.40157598456, 60300.0, 60377.09434358532, 60669.0, 10350.378977261389, 10364.119049880694, 10646.73383165427, 10684.51919663617, 10967.670969870916, 20344.422504936272, 20352.28766993148, 20414.591708165754, 20524.38426847197, 20677.27629532959, 6635.369639884203, 6665.766698165541, 6752.3922846944015, 6781.25406205317, 6793.762218322335, 3503.2468019375956, 3512.9121771955906, 3520.859165633346, 3603.387424137903, 3607.4228123500116, 2961.585378047075, 2988.3873805869744, 3028.4094601552924, 3052.862621182008, 3063.5098384258413, 473.8720545452708, 474.3193517302682, 481.9759012049454, 482.1971417589674, 488.028822642938, 636.5098873867097, 641.7785863876956, 653.633964979613, 664.2394458345168, 667.5727534378002, 8193.426460147852, 8260.793480163044, 8444.662213511514, 8475.70335038283, 8630.0, 559.1808001278147, 581.9301683797971, 585.1281590430228, 590.4626789621424, 591.1546488521456, 4115.97942010288, 4259.574042595777, 4287.6998610097, 4309.61213490781, 4380.956190438119, 57460.42539574551, 57578.0, 59162.40837591569, 61679.38320616737, 61939.380606193365, 45990.0, 49998.00003999972, 53571.0, 56308.71845640828, 57519.71240143856, 49223.507764921895, 49454.0, 49704.75147624239, 49754.751226243636, 50066.74966625144, 8049.356051515982, 8235.794105147179, 8298.668053277801, 8430.704925327584, 8459.83080338402, 33573.83213083919, 33683.0, 33768.324633507684, 35533.64466355407, 36154.10767138675, 39625.603743963344, 42556.36165457459, 43085.0, 44207.0, 44904.32643510285, 10142.137918276734, 10307.94846025795, 10314.638987635397, 10315.228798289234, 10412.843807342744, 11311.151663625096, 11410.543578256777, 11467.197296189186, 11711.414429278673, 12163.087768417226, 10037.397756134655, 10072.647457338959, 10153.492325383553, 10222.53998570081, 10621.097206737204, 9713.0, 9832.55753491109, 9890.752731181778, 9894.406335619886, 10150.970524802242, 9537.618495260112, 9607.51962401891, 9866.654667086617, 9893.208543316661, 10273.845892311472, 8539.487630742175, 8891.110888911317, 8912.955435223043, 8917.777055573662, 9187.77030574241, 7380.852382952418, 7593.240675932362, 7775.883361749465, 8239.752807415787, 8361.997094518083], "value": 6108.220917044206, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 118.0, 117.0, 118.0, 118.0, 121.0, 116.0, 117.0, 120.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 130.0, 133.0, 174.0, 133.0, 133.0, 132.0, 127.0, 158.0, 127.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 169.0, 168.0, 165.0, 164.0, 165.0, 163.0, 165.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 109.0, 80.0, 80.0, 79.0, 78.0, 95.0, 76.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 115.0, 87.0, 86.0, 88.0, 102.0, 88.0, 87.0, 114.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 116.0, 114.0, 114.0, 115.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 150.0, 152.0, 150.0, 151.0, 151.0, 151.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 68.0, 66.0, 66.0, 68.0, 69.0, 65.0, 67.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 91.0, 87.0, 96.0, 88.0, 88.0, 87.0, 94.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 140.0, 154.0, 152.0, 141.0, 147.0, 139.0, 146.0, 189.0, 149.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 188.0, 189.0, 191.0, 197.0, 187.0, 194.0, 187.0, 192.0, 188.0], "value": 190.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 74.0, 73.0, 77.0, 75.0, 74.0, 75.0, 78.0, 76.0], "value": 75.0, "unit": "score"}]}]} 04:38:15 INFO - Return code: 0 04:38:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:38:15 ERROR - # TBPL SUCCESS # 04:38:15 INFO - Running post-action listener: _resource_record_post_action 04:38:15 INFO - [mozharness: 2017-02-17 12:38:15.801796Z] Finished run-tests step (success) 04:38:15 INFO - Running post-run listener: _resource_record_post_run 04:38:15 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 370847744; Read time: 152; Write time: 166696 04:38:15 INFO - TinderboxPrint: CPU usage
13.5% 04:38:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 152 04:38:15 INFO - TinderboxPrint: I/O write bytes / time
370,847,744 / 166,696 04:38:15 INFO - TinderboxPrint: CPU idle
8,299.2 (86.5%) 04:38:15 INFO - TinderboxPrint: CPU user
1,235.0 (12.9%) 04:38:15 INFO - TinderboxPrint: Swap in / out
0 / 0 04:38:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 116228096; Read time: 152; Write time: 45832 04:38:15 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 254619648; Read time: 0; Write time: 120864 04:38:16 INFO - Running post-run listener: _upload_blobber_files 04:38:16 INFO - Blob upload gear active. 04:38:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:38:16 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 04:38:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:38:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:38:17 INFO - (blobuploader) - INFO - Open directory for files ... 04:38:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:38:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:19 INFO - (blobuploader) - INFO - Done attempting. 04:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:20 INFO - (blobuploader) - INFO - Done attempting. 04:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:34 INFO - (blobuploader) - INFO - Done attempting. 04:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:35 INFO - (blobuploader) - INFO - Done attempting. 04:38:35 INFO - (blobuploader) - INFO - Iteration through files over. 04:38:35 INFO - Return code: 0 04:38:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:38:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:38:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/092c3e88c0de3d3ad020ef9cfc8873163445bc72f28e420b22aef3284f85ea75a03c3aa1d12b15928e8002d07a39af6e3ac768d70de92d1b129ef8d561699965", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aecc4b4604353312632c2b30d7135b807ddfbc5d3cfc9863fdead8efa42e44b613d0a29206188bf29e7089bba7b58f0ad9b392c5da6c107848f666e1f556e37d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18682f8d2aba04879b29944939a33f6ec87deb385e7c3f24678c5ca0d3076cd4c8bbb827d904a9296d03d8be8a35e885a6c0c40a8684873a6071f52a7465ce69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ba6ccf542f953f73302bf9ecc699b37d892f4c71af278f432f5bf557794eac55be9919cc5153f5bb82dc276c62619236da0b0863e0782bad5570f3583aefbd7"} 04:38:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:38:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:38:35 INFO - Contents: 04:38:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/092c3e88c0de3d3ad020ef9cfc8873163445bc72f28e420b22aef3284f85ea75a03c3aa1d12b15928e8002d07a39af6e3ac768d70de92d1b129ef8d561699965", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aecc4b4604353312632c2b30d7135b807ddfbc5d3cfc9863fdead8efa42e44b613d0a29206188bf29e7089bba7b58f0ad9b392c5da6c107848f666e1f556e37d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18682f8d2aba04879b29944939a33f6ec87deb385e7c3f24678c5ca0d3076cd4c8bbb827d904a9296d03d8be8a35e885a6c0c40a8684873a6071f52a7465ce69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ba6ccf542f953f73302bf9ecc699b37d892f4c71af278f432f5bf557794eac55be9919cc5153f5bb82dc276c62619236da0b0863e0782bad5570f3583aefbd7"} 04:38:35 INFO - Running post-run listener: copy_logs_to_upload_dir 04:38:35 INFO - Copying logs to upload dir... 04:38:35 INFO - mkdir: /builds/slave/test/build/upload/logs 04:38:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.997680 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-02-17 04:38:35.695986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 04:38:35.699491) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/092c3e88c0de3d3ad020ef9cfc8873163445bc72f28e420b22aef3284f85ea75a03c3aa1d12b15928e8002d07a39af6e3ac768d70de92d1b129ef8d561699965", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aecc4b4604353312632c2b30d7135b807ddfbc5d3cfc9863fdead8efa42e44b613d0a29206188bf29e7089bba7b58f0ad9b392c5da6c107848f666e1f556e37d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18682f8d2aba04879b29944939a33f6ec87deb385e7c3f24678c5ca0d3076cd4c8bbb827d904a9296d03d8be8a35e885a6c0c40a8684873a6071f52a7465ce69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ba6ccf542f953f73302bf9ecc699b37d892f4c71af278f432f5bf557794eac55be9919cc5153f5bb82dc276c62619236da0b0863e0782bad5570f3583aefbd7"} build_url:https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/dUd_aWnLQECS5ytsDg5CnQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/092c3e88c0de3d3ad020ef9cfc8873163445bc72f28e420b22aef3284f85ea75a03c3aa1d12b15928e8002d07a39af6e3ac768d70de92d1b129ef8d561699965", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aecc4b4604353312632c2b30d7135b807ddfbc5d3cfc9863fdead8efa42e44b613d0a29206188bf29e7089bba7b58f0ad9b392c5da6c107848f666e1f556e37d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18682f8d2aba04879b29944939a33f6ec87deb385e7c3f24678c5ca0d3076cd4c8bbb827d904a9296d03d8be8a35e885a6c0c40a8684873a6071f52a7465ce69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ba6ccf542f953f73302bf9ecc699b37d892f4c71af278f432f5bf557794eac55be9919cc5153f5bb82dc276c62619236da0b0863e0782bad5570f3583aefbd7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 04:38:35.724033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:38:35.724364) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1487333840.476085-132772017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 04:38:35.806952) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 04:38:35.807283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 04:38:35.807580) ========= ========= Total master_lag: 0.25 =========